Welcome! Log In Create A New Profile

Advanced

Problem booting Pogo Pro 4.4.38-oxnas-tld-5 with Intel SSDSC2BF180A4L

Posted by schnee 
Problem booting Pogo Pro 4.4.38-oxnas-tld-5 with Intel SSDSC2BF180A4L
February 06, 2017 04:39PM
Hi bodhi,
it is really great that you don'T let our small plug computer to become obsolete! Huge thanks for that!

I just recently installed 4.4.38 on all my pogoplugs and now they work fine. So far i used 160 GB 2.5' SATA drives as i boot completely from SATA. Some of those drives i replaced with 160 GB SSD and everything went fine. Now i received a 180 GB SSD and the trouble started:
1. If i install the 3.17 root image it boots fine and works fine on the 180GB SSD
2. if I upgrade the kernel to 4.0 it still works fine it still works fine
3. If i upgrade to 4.1 or 4.4.38 then it hangs on boot.

SSD: Intel SSD Pro 1500 Series (180GB)

the error is around the sata as i see some timeouts in the log:
[    3.070003] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
[    3.087119] ata1.00: ATA-9: INTEL SSDSC2BF180A4L, LADi, max UDMA/133
[    3.093496] ata1.00: 351651888 sectors, multi 16: LBA48 NCQ (depth 0/32)
[   18.100001] ata1.00: qc timeout (cmd 0xef)
[   18.104084] sata_oxnas: resetting SATA core
[   18.460001] ata1.00: failed to set xfermode (err_mask=0x5)
[   18.969999] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
[   33.990005] ata1.00: qc timeout (cmd 0xef)
......

Attached the whole boot log to the message. I will try to see if i can upgrade the SSD firmware and if it helps, but otherwise i have no ide what is going wrong.



Edited 1 time(s). Last edit at 02/07/2017 01:02AM by bodhi.
Attachments:
open | download - kernel4.4.38.log (13.2 KB)
Re: Problem booting Pogo Pro 4.4.38-oxnas-tld-5 with Intel SSDSC2BF180A4L
February 07, 2017 01:12AM
schnee,

The log showed you have not installed the new u-boot.

However, I'm not sure yet why your SSD works in older kernel but not the new one. I'll take a look to see if I can tell if the sata-oxnas driver in kernel 4.4.38 have changed something that could cause this.

You could try to boot with a USB drive using a new rootfs with with kernel 3.17, and then with 4.4.38, to see if the SSD is recognized after the system is running. That would add a hint to where I should look for the differences.

-bodhi
===========================
Forum Wiki
bodhi's corner (buy bodhi a beer)
Re: Problem booting Pogo Pro 4.4.38-oxnas-tld-5 with Intel SSDSC2BF180A4L
February 07, 2017 04:19PM
bodhi,

i installed today the new uboot on my sata drive with partial success. The new uboot loads, but i still use the old SPL from warehead. The results are the same -> fine boot with 3.17, no boot with 4.4. The main difference is that now it drops to busybox, instead of a loop trying to boot. The error is get is:

[    2.853009] Freeing unused kernel memory: 228K (c080f000 - c0848000)         
Loading, please wait...                                                         
[    2.998254] udevd[94]: starting version 175                                  
Begin: Loading essential drivers ... done.                                      
Begin: Running /scripts/init-premount ... done.                                 
Begin: Mounting root file system ... Begin: Running /scripts/local-top ... done.
Begin: Waiting for root file system ... done.                                   
Gave up waiting for root device.  Common problems:                              
 - Boot args (cat /proc/cmdline)                                                
   - Check rootdelay= (did the system wait long enough?)                        
   - Check root= (did the system wait for the right device?)                    
 - Missing modules (cat /proc/modules; ls /dev)                                 
ALERT!  /dev/sda2 does not exist.  Dropping to a shell!                         
modprobe: module i8042 not found in modules.dep                                 
modprobe: module uhci-hcd not found in modules.dep                              
modprobe: module ohci-hcd not found in modules.dep                              
                                                                                
                                                                                
BusyBox v1.20.2 (Debian 1:1.20.0-7) built-in shell (ash)                        
Enter 'help' for a list of built-in commands.                                   
                                                                                
/bin/sh: can't access tty; job control turned off                               
(initramfs)
(initramfs) cd /dev                                                             
(initramfs) cat /proc/modules                                                   
(initramfs)

attached both the 3.17 and the 4.4 boot log.

I will try to test with USB soon too.
Attachments:
open | download - new3.17.log (16.5 KB)
open | download - new4.4.log (12.3 KB)
Re: Problem booting Pogo Pro 4.4.38-oxnas-tld-5 with Intel SSDSC2BF180A4L
February 07, 2017 05:55PM
schnee,

> i installed today the new uboot on my sata drive w
> ith partial success. The new uboot loads, but i st
> ill use the old SPL from warehead.

It is OK to run WarheadSE old SPL. U-boot is really more important to replace.

> I will try to test with USB soon too.

Before you do that, a couple things to make sure that were not the cause:

1. Your env is not right: rootdelay=0. Set it to 10 like you did before.

2. Verify that you run the correct version of the DTB. When running kernel 4.1 you need the DTB that comes with it, and running kernel 4.4.38 you need the later DTB version that comes with it.

ls -lart /boot/dts/ox820-pogoplug*.dtb

-bodhi
===========================
Forum Wiki
bodhi's corner (buy bodhi a beer)
Re: Problem booting Pogo Pro 4.4.38-oxnas-tld-5 with Intel SSDSC2BF180A4L
February 07, 2017 06:03PM
Also, another thing.

Did you use the new u-boot envs image? I noticed you are still using /dev/sda2 as the root device.

If you have "flashed" (i.e. put it in the SSD) the new u-boot default envs, then you would have used root=LABEL=rootfs for the root device. And the sda2 partition should be labeled rootfs.

-bodhi
===========================
Forum Wiki
bodhi's corner (buy bodhi a beer)
Re: Problem booting Pogo Pro 4.4.38-oxnas-tld-5 with Intel SSDSC2BF180A4L
February 08, 2017 02:08PM
Hi bodhi,

thanks for the reminder. I indeed forgot to update the dts files. I did it now, the result is the same, but the time to get there is longer - attached the boot log. Also edited the envs to use rootfs and 10 sec delay. I flashed your envs to the ssd, but it did not work, so it started with defaults.

current uboot.env

autoload=no
baudrate=115200
bootargs=console=ttyS0,115200n8
bootcmd=run bootcmd_uenv; run dt_bootcmd_usb; reset
bootcmd_uenv=run uenv_load; if test $uenv_loaded -eq 1; then run uenv_import; fi
bootdelay=10
console=console=ttyS0,115200n8
devices=usb ide
dt_bootcmd_usb=usb start; run dt_usb_bootcmd; usb stop; reset
dt_bootm=bootm $uimage_addr $uinitrd_addr $dtb_addr
dt_load_dtb=ext2load ide 0:2 $dtb_addr /boot/dts/ox820-pogoplug-pro.dtb
dt_load_initrd=ext2load ide 0:2 $uinitrd_addr /boot/uInitrd
dt_load_uimage=ext2load ide 0:2 $uimage_addr /boot/uImage
dt_usb_boot=run dt_load_uimage; run dt_load_initrd; run dt_load_dtb; run dt_bootm
dt_usb_bootcmd=run usb_set_bootargs; run dt_usb_boot
dtb_addr=0x62c00000
ethact=dwmac.40400000
ethaddr=5e:2b:15:71:ec:b6
if_netconsole=ping $serverip
ipaddr=192.168.0.222
mtdids=nand0=41000000.nand
mtdparts=mtdparts=41000000.nand:14m(boot),-(data)
preboot_nc=run if_netconsole start_netconsole
serverip=192.168.0.220
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 0x60500000
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=echo loading envs from $devtype $disknum ...; if load $devtype $disknum:1 0x60500000 /boot/uEnv.txt; then setenv uenv_loaded 1; fi
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
uimage_addr=0x60500000
uinitrd_addr=0x60e00000
usb_device=0:1
usb_set_bootargs=console=ttyS0,115200 root=LABEL=rootfs rootdelay=10

Environment size: 1975/131068 bytes
Attachments:
open | download - kernel4.4.correct.dtb.log (18.8 KB)
Re: Problem booting Pogo Pro 4.4.38-oxnas-tld-5 with Intel SSDSC2BF180A4L
February 08, 2017 02:43PM
schnee,

This time there are 2 problems: 1) the u-boot envs. 2) the SSD could not be initialized properly (which is the same as before).

1)
The bootargs was not set properly
usb_set_bootargs=console=ttyS0,115200 root=LABEL=rootfs rootdelay=10

Should be:
usb_set_bootargs=setenv bootargs "console=ttyS0,115200 root=LABEL=rootfs rootdelay=10"

2)
The SATA initialization might be because a change in driver. SSD sometime is picky (HDD is never so). Some SSD could never be used for booting. In this case, apparently kernel 4.1 works, so it might be tthe difference in sata_oxnas driver between 2 kernels.

I think the best way to solve this SATA problem is either:

1. Try using another SSD or HDD to see if same symptom occurs.
2. Boot with USB and then plug in the SSD to see if it can be initialized after the system is running.

If number 2 is still not working then I'll take a look at the driver.

-bodhi
===========================
Forum Wiki
bodhi's corner (buy bodhi a beer)
Re: Problem booting Pogo Pro 4.4.38-oxnas-tld-5 with Intel SSDSC2BF180A4L
February 08, 2017 05:34PM
bodhi,

corrected the bootargs, but the result is the same

Quote

2) The SATA initialization might be because a change in driver. SSD sometime is picky (HDD is never so). Some SSD could never be used for booting. In this case, apparently kernel 4.1 works, so it might be tthe difference in sata_oxnas driver between 2 kernels.

I think the best way to solve this SATA problem is either:

1. Try using another SSD or HDD to see if same symptom occurs.
2. Boot with USB and then plug in the SSD to see if it can be initialized after the system is running.

If number 2 is still not working then I'll take a look at the driver.

This SSD works with 4.0, but it does not work with 4.1 and 4.4 kernels. I tried other HDD HTS723216L9SA60 (160G) and an SSD Intel SSD 320 series 160 GB and both works fine.

Will test with USB soon.
Re: Problem booting Pogo Pro 4.4.38-oxnas-tld-5 with Intel SSDSC2BF180A4L
February 08, 2017 06:10PM
schnee,

> This SSD works with 4.0, but it does not work with
> 4.1 and 4.4 kernels.

> I tried other HDD HTS723216L9
> SA60 (160G) and an SSD Intel SSD 320 series 160 GB
> and both works fine.

Thanks for the correction!

This is very good info. It's proved that the Intel SSDSC2BF180A4L has some quirks that only surface in later kernel 4.1+. Especially is that 4.0 and 4.1 use the same driver (IIRC, have not doublechecked) . So that narrow down the issue quite a bit more.

-bodhi
===========================
Forum Wiki
bodhi's corner (buy bodhi a beer)



Edited 1 time(s). Last edit at 02/08/2017 08:40PM by bodhi.
Re: Problem booting Pogo Pro 4.4.38-oxnas-tld-5 with Intel SSDSC2BF180A4L
February 13, 2017 01:56PM
I was a bit busy with some other things, so i only got back to testing today..

I made my pogo to boot from USB and attached the disk to it after it booted up. The result is the same, the drive is failing with "ata1.00: failed to set xfermode (err_mask=0x5)"

full log output on the serial console:
[   88.996234] sata_oxnas: resetting SATA core
[   89.010711] ata1: exception Emask 0x10 SAct 0x0 SErr 0x20000 action 0xe frozen
[   89.017907] ata1: SError: { PHYInt }
[   89.021539] ata1: hard resetting link
[   89.930022] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
[   89.946247] ata1.00: ATA-9: INTEL SSDSC2BF180A4L, LADi, max UDMA/133
[   89.952616] ata1.00: 351651888 sectors, multi 16: LBA48 NCQ (depth 0/32)
[  104.960040] ata1.00: qc timeout (cmd 0xef)
[  104.964124] sata_oxnas: resetting SATA core
[  105.320035] ata1.00: failed to set xfermode (err_mask=0x5)
[  105.325511] ata1: hard resetting link
[  105.830026] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
[  120.850040] ata1.00: qc timeout (cmd 0xef)
[  120.854126] sata_oxnas: resetting SATA core
[  121.210035] ata1.00: failed to set xfermode (err_mask=0x5)
[  121.215513] ata1.00: limiting speed to UDMA/133:PIO3
[  121.220523] ata1: hard resetting link
[  121.730030] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
[  136.750033] ata1.00: qc timeout (cmd 0xef)
[  136.754117] sata_oxnas: resetting SATA core
[  137.110039] ata1.00: failed to set xfermode (err_mask=0x5)
[  137.115507] ata1.00: disabled
[  137.118479] sata_oxnas: resetting SATA core
[  137.132956] ata1: exception Emask 0x10 SAct 0x0 SErr 0x20000 action 0xe frozen t4
[  137.140436] ata1: SError: { PHYInt }
[  137.144017] ata1: hard resetting link
[  138.050032] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
[  138.067128] ata1.00: ATA-9: INTEL SSDSC2BF180A4L, LADi, max UDMA/133
[  138.073500] ata1.00: 351651888 sectors, multi 16: LBA48 NCQ (depth 0/32)
[  153.080184] ata1.00: qc timeout (cmd 0xef)
[  153.084267] sata_oxnas: resetting SATA core
[  153.440038] ata1.00: failed to set xfermode (err_mask=0x5)
[  153.445514] ata1: hard resetting link
[  153.950036] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
[  168.970048] ata1.00: qc timeout (cmd 0xef)
[  168.974133] sata_oxnas: resetting SATA core
[  169.330048] ata1.00: failed to set xfermode (err_mask=0x5)
[  169.335526] ata1.00: limiting speed to UDMA/133:PIO3
[  169.340538] ata1: hard resetting link
[  169.850042] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
[  184.870049] ata1.00: qc timeout (cmd 0xef)
[  184.874129] sata_oxnas: resetting SATA core
[  185.230046] ata1.00: failed to set xfermode (err_mask=0x5)
[  185.235517] ata1.00: disabled
[  185.238487] sata_oxnas: resetting SATA core
[  185.252953] ata1: exception Emask 0x10 SAct 0x0 SErr 0x20000 action 0xe frozen t3
[  185.260431] ata1: SError: { PHYInt }
[  185.264010] ata1: hard resetting link
[  186.170048] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
[  186.187148] ata1.00: ATA-9: INTEL SSDSC2BF180A4L, LADi, max UDMA/133
[  186.193523] ata1.00: 351651888 sectors, multi 16: LBA48 NCQ (depth 0/32)
[  201.200056] ata1.00: qc timeout (cmd 0xef)
[  201.204142] sata_oxnas: resetting SATA core
[  201.560051] ata1.00: failed to set xfermode (err_mask=0x5)
[  201.565527] ata1: hard resetting link
[  202.070049] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
[  217.090068] ata1.00: qc timeout (cmd 0xef)
[  217.094151] sata_oxnas: resetting SATA core
[  217.450055] ata1.00: failed to set xfermode (err_mask=0x5)
[  217.455528] ata1.00: limiting speed to UDMA/133:PIO3
[  217.460541] ata1: hard resetting link
[  217.970050] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
[  232.990069] ata1.00: qc timeout (cmd 0xef)
[  232.994152] sata_oxnas: resetting SATA core
[  233.350059] ata1.00: failed to set xfermode (err_mask=0x5)
[  233.355525] ata1.00: disabled
[  233.358494] sata_oxnas: resetting SATA core
[  233.372945] ata1: exception Emask 0x10 SAct 0x0 SErr 0x20000 action 0xe frozen t2
[  233.380441] ata1: SError: { PHYInt }
[  233.384017] ata1: hard resetting link
[  234.290058] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
[  234.307159] ata1.00: ATA-9: INTEL SSDSC2BF180A4L, LADi, max UDMA/133
[  234.313533] ata1.00: 351651888 sectors, multi 16: LBA48 NCQ (depth 0/32)
[  249.320069] ata1.00: qc timeout (cmd 0xef)
[  249.324152] sata_oxnas: resetting SATA core
[  249.680061] ata1.00: failed to set xfermode (err_mask=0x5)
[  249.685539] ata1: hard resetting link
[  250.190246] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
[  265.210071] ata1.00: qc timeout (cmd 0xef)
[  265.214156] sata_oxnas: resetting SATA core
[  265.570076] ata1.00: failed to set xfermode (err_mask=0x5)
[  265.575552] ata1.00: limiting speed to UDMA/133:PIO3
[  265.580567] ata1: hard resetting link
[  266.090071] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
[  281.110072] ata1.00: qc timeout (cmd 0xef)
[  281.114155] sata_oxnas: resetting SATA core
[  281.470080] ata1.00: failed to set xfermode (err_mask=0x5)
[  281.475548] ata1.00: disabled
[  281.478520] sata_oxnas: resetting SATA core
[  281.492968] ata1: exception Emask 0x10 SAct 0x0 SErr 0x20000 action 0xe frozen t1
[  281.500449] ata1: SError: { PHYInt }
[  281.504028] ata1: hard resetting link
[  282.410068] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
[  282.427171] ata1.00: ATA-9: INTEL SSDSC2BF180A4L, LADi, max UDMA/133
[  282.433543] ata1.00: 351651888 sectors, multi 16: LBA48 NCQ (depth 0/32)
[  297.440081] ata1.00: qc timeout (cmd 0xef)
[  297.444165] sata_oxnas: resetting SATA core
[  297.800076] ata1.00: failed to set xfermode (err_mask=0x5)
[  297.805555] ata1: hard resetting link
[  298.310069] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
[  313.330094] ata1.00: qc timeout (cmd 0xef)
[  313.334177] sata_oxnas: resetting SATA core
[  313.690076] ata1.00: failed to set xfermode (err_mask=0x5)
[  313.695552] ata1.00: limiting speed to UDMA/133:PIO3
[  313.700567] ata1: hard resetting link
[  314.210081] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
[  329.230082] ata1.00: qc timeout (cmd 0xef)
[  329.234163] sata_oxnas: resetting SATA core
[  329.590085] ata1.00: failed to set xfermode (err_mask=0x5)
[  329.595558] ata1.00: disabled
[  329.598525] ata1: EH pending after 5 tries, giving up
[  329.603642] ata1: EH complete

with kernel 3.17 there are no issues:
[   51.559137] ox820sata: reseting SATA core
[   51.573652] ata1: exception Emask 0x10 SAct 0x0 SErr 0x20000 action 0xe frozen
[   51.580887] ata1: SError: { PHYInt }
[   51.584465] ata1: hard resetting link
[   52.490032] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
[   52.507133] ata1.00: ATA-9: INTEL SSDSC2BF180A4L, LADi, max UDMA/133
[   52.513502] ata1.00: 351651888 sectors, multi 16: LBA48 NCQ (depth 0/32)
[   52.547161] ata1.00: configured for UDMA/133
[   52.551470] ata1: EH complete
[   52.554867] scsi 0:0:0:0: Direct-Access     ATA      INTEL SSDSC2BF18 LADi PQ: 0 ANSI: 5
[   52.569021] sd 0:0:0:0: [sdb] 351651888 512-byte logical blocks: (180 GB/167 GiB)
[   52.578644] sd 0:0:0:0: [sdb] Write Protect is off
[   52.583772] sd 0:0:0:0: [sdb] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
[   52.602112]  sdb: sdb1 sdb2 sdb3
[   52.618211] sd 0:0:0:0: [sdb] Attached SCSI disk

Hope this helps to pinpoint the issue with the drive.

PS: i looked at intel's site and there is no firmware update for this drive (so i hope it is not a firmware issue)
Re: Problem booting Pogo Pro 4.4.38-oxnas-tld-5 with Intel SSDSC2BF180A4L
February 14, 2017 12:45AM
schnee,

I'll take a look at the SATA driver difference when I start building kernel 4.9 for this box.

4.9 is an LTS kernel so that's my current target for Popgo V3 Oxnas.

-bodhi
===========================
Forum Wiki
bodhi's corner (buy bodhi a beer)
Re: Problem booting Pogo Pro 4.4.38-oxnas-tld-5 with Intel SSDSC2BF180A4L
March 22, 2017 04:27PM
Hi Bodhi,

i see that you released new kernels in the 4.4.X line. By any chance did you look into the sata driver? Does it make sense to start testing again with the problematic HDD?

Thanks

schnee
Re: Problem booting Pogo Pro 4.4.38-oxnas-tld-5 with Intel SSDSC2BF180A4L
March 22, 2017 05:28PM
schnee,

Sorry I forgot about this! too many things on my mind, guess I should start keeping/reviewing a TODO list again :).

This kernel release was just to incorporate the latest Linux 4.4.x stable kernel series (so there is no change in any drivers). But everybody should upgrade any way.

-bodhi
===========================
Forum Wiki
bodhi's corner (buy bodhi a beer)
Re: Problem booting Pogo Pro 4.4.38-oxnas-tld-5 with Intel SSDSC2BF180A4L
March 22, 2017 06:10PM
schnee,

I've just taken a peek at the SATA drivers between 2 versions that could have cause this error.

The mainline libata-core driver (common to all architecture) has changed quite a bit in dealing with SSD, and there were some changes in Intel SSDs.

I don't recall if you have tried this SSD on another box as SATA drive?

-bodhi
===========================
Forum Wiki
bodhi's corner (buy bodhi a beer)
Re: Problem booting Pogo Pro 4.4.38-oxnas-tld-5 with Intel SSDSC2BF180A4L
March 22, 2017 06:27PM
> I don't recall if you have tried this SSD on anoth
> er box as SATA drive?

Bodhi,

i used to use this SATA SSD in a laptop with ubuntu linux (12.04LTS) in the past and it worked fine. I did not try it recently though.

If i attach this as a normal SATA drive to my 14.04 Ubuntu desktop it works fine.

Schnee
Re: Problem booting Pogo Pro 4.4.38-oxnas-tld-5 with Intel SSDSC2BF180A4L
March 22, 2017 09:20PM
schnee Wrote:
-------------------------------------------------------
> > I don't recall if you have tried this SSD on ano
> th
> > er box as SATA drive?
>
> Bodhi,
>
> i used to use this SATA SSD in a laptop with ubunt
> u linux (12.04LTS) in the past and it worked fine.
> I did not try it recently though.
>
> If i attach this as a normal SATA drive to my 14.0
> 4 Ubuntu desktop it works fine.
>
> Schnee

Those might use older kernel than 4.4.x.

-bodhi
===========================
Forum Wiki
bodhi's corner (buy bodhi a beer)
Re: Problem booting Pogo Pro 4.4.38-oxnas-tld-5 with Intel SSDSC2BF180A4L
March 23, 2017 04:20PM
I did some test today with debian:

Installed debian stable --> 3.18 kernel --> upgraded to strech --> 4.9.0.2-amd64 kernel --> reboot

The system works fine. So either the driver is fixed in 4.9 or it would work fine with any 4.X kernel in debian-amd64
Re: Problem booting Pogo Pro 4.4.38-oxnas-tld-5 with Intel SSDSC2BF180A4L
June 20, 2017 04:53PM
Today i played with an other pogoplud with a SSD Kingstone SHS450S3/32G

It works with kernel 4.1.0, but does not boot on 4.4.38 or 4.4.54

This is the console output:
[    1.139781] Key type encrypted registered
[    1.160573] UBI error: cannot open mtd data, error -2VFS: Cannot open root device "sda2" or unknown-bl
ock(0,0): error -6
[    1.173859] Please append a correct "root=" boot option; here are the available partitions:
[    1.182282] Kernel panic - not syncing: VFS: Unable to mount root fs on unknown-block(0,0)
[    1.190517] CPU: 0 PID: 1 Comm: swapper/0 Not tainted 4.4.38-oxnas-tld-5 #1
[    1.197442] Hardware name: PLXTECH NAS782X SoC (Flattened Device Tree)
[    1.203965] [<c0010c4c>] (unwind_backtrace) from [<c000cf70>] (show_stack+0x10/0x14)
[    1.211686] [<c000cf70>] (show_stack) from [<c02cef00>] (dump_stack+0x84/0xa0)
[    1.218882] [<c02cef00>] (dump_stack) from [<c0091bf4>] (panic+0x8c/0x1ec)
[    1.225733] [<c0091bf4>] (panic) from [<c0810188>] (mount_block_root+0x1a0/0x258)
[    1.233187] [<c0810188>] (mount_block_root) from [<c0810338>] (mount_root+0xf8/0x124)
[    1.240983] [<c0810338>] (mount_root) from [<c0810494>] (prepare_namespace+0x130/0x194)
[    1.248954] [<c0810494>] (prepare_namespace) from [<c080fe04>] (kernel_init_freeable+0x198/0x1e4)
[    1.257797] [<c080fe04>] (kernel_init_freeable) from [<c0603728>] (kernel_init+0x8/0xec)
[    1.265858] [<c0603728>] (kernel_init) from [<c000a508>] (ret_from_fork+0x14/0x2c)
[    1.273402] CPU1: stopping
[    1.276108] CPU: 1 PID: 0 Comm: swapper/1 Not tainted 4.4.38-oxnas-tld-5 #1
[    1.283033] Hardware name: PLXTECH NAS782X SoC (Flattened Device Tree)
[    1.289548] [<c0010c4c>] (unwind_backtrace) from [<c000cf70>] (show_stack+0x10/0x14)
[    1.297267] [<c000cf70>] (show_stack) from [<c02cef00>] (dump_stack+0x84/0xa0)
[    1.304463] [<c02cef00>] (dump_stack) from [<c000fb64>] (handle_IPI+0x190/0x1c4)
[    1.311831] [<c000fb64>] (handle_IPI) from [<c0009468>] (gic_handle_irq+0x74/0x7c)
[    1.319372] [<c0009468>] (gic_handle_irq) from [<c000da00>] (__irq_svc+0x40/0x74)
[    1.326814] Exception stack(0xc7869f88 to 0xc7869fd0)
[    1.331845] 9f80:                   00000001 00000000 00000000 00000000 00000000 c060862c
[    1.339985] 9fa0: 00000000 c7869fe0 c08422c4 c08506ac c7868000 00000000 00000000 c7869fd8
[    1.348124] 9fc0: c000af3c c000af40 60000013 ffffffff
[    1.353157] [<c000da00>] (__irq_svc) from [<c000af40>] (arch_cpu_idle+0x28/0x30)
[    1.360529] [<c000af40>] (arch_cpu_idle) from [<c004d290>] (cpu_startup_entry+0x14c/0x260)
[    1.368762] [<c004d290>] (cpu_startup_entry) from [<60009508>] (0x60009508)
[    1.375700] ---[ end Kernel panic - not syncing: VFS: Unable to mount root fs on unknown-block(0,0)
Re: Problem booting Pogo Pro 4.4.38-oxnas-tld-5 with Intel SSDSC2BF180A4L
June 20, 2017 05:27PM
schnee,

Could you post the entire serial console log?

-bodhi
===========================
Forum Wiki
bodhi's corner (buy bodhi a beer)
Re: Problem booting Pogo Pro 4.4.38-oxnas-tld-5 with Intel SSDSC2BF180A4L
June 20, 2017 06:00PM
here you are

OX820 # run ext4_ideboot                                                        
4621824 bytes read in 825 ms (5.3 MiB/s)                                        
7033 bytes read in 29 ms (236.3 KiB/s)                                          
magic:                  0xd00dfeed                                              
totalsize:              0x1b79 (7033)                                           
off_dt_struct:          0x38                                                    
off_dt_strings:         0x18d4                                                  
off_mem_rsvmap:         0x28                                                    
version:                17                                                      
last_comp_version:      16                                                      
boot_cpuid_phys:        0x0                                                     
size_dt_strings:        0x2a5                                                   
size_dt_struct:         0x189c                                                  
number mem_rsv:         0x0                                                     
                                                                                
## Booting kernel from Legacy Image at 60000000 ...                             
   Image Name:   Linux-4.4.54-oxnas-tld-1                                       
   Image Type:   ARM Linux Kernel Image (uncompressed)                          
   Data Size:    4621760 Bytes = 4.4 MiB                                        
   Load Address: 60008000                                                       
   Entry Point:  60008000                                                       
   Verifying Checksum ... OK                                                    
## Flattened Device Tree blob at 61000000                                       
   Booting using the fdt blob at 0x61000000                                     
   Loading Kernel Image ... OK                                                  
   Loading Device Tree to 67e26000, end 67e2ab78 ... OK                         
                                                                                
Starting kernel ...                                                             
                                                                                
Uncompressing Linux... done, booting the kernel.                                
[    0.000000] Booting Linux on physical CPU 0x0                                
[    0.000000] Linux version 4.4.54-oxnas-tld-1 (root@tldDebian) (gcc version 47
[    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 Pro                                      
[    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 s17696 r8192 d23264 u4912
[    0.000000] Built 1 zonelists in Zone order, mobility grouping on.  Total pa2
[    0.000000] Kernel command line: console=ttyS0,115200n8 root=/dev/sda2 rootf2
[    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: 120696K/131072K available (6410K kernel code, 288K 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 - 0xc0827e14   (8320 kB)                
[    0.000000]       .init : 0xc0828000 - 0xc0860000   ( 224 kB)                
[    0.000000]       .data : 0xc0860000 - 0xc08a8378   ( 289 kB)                
[    0.000000]        .bss : 0xc08a8378 - 0xc08e6350   ( 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: 0s
[    0.000007] sched_clock: 24 bits at 390kHz, resolution 2560ns, wraps every 2s
[    0.000174] Console: colour dummy device 80x30                               
[    0.000207] Calibrating delay loop... 319.48 BogoMIPS (lpj=1597440)          
[    0.050030] pid_max: default: 32768 minimum: 301                             
[    0.050204] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes)      
[    0.050224] Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes) 
[    0.051095] Initializing cgroup subsys net_cls                               
[    0.051179] CPU: Testing write buffer coherency: ok                          
[    0.051630] Setting up static identity map for 0x60008220 - 0x60008258       
[    0.190151] Brought up 2 CPUs                                                
[    0.190174] SMP: Total of 2 processors activated (638.97 BogoMIPS).          
[    0.191009] devtmpfs: initialized                                            
[    0.196894] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, ms
[    0.196943] futex hash table entries: 512 (order: 2, 16384 bytes)            
[    0.197273] prandom: seed boundary self test passed                          
[    0.201966] prandom: 100 self tests passed                                   
[    0.201996] pinctrl core: initialized pinctrl subsystem                      
[    0.202872] NET: Registered protocol family 16                               
[    0.203461] DMA: preallocated 256 KiB pool for atomic coherent allocations   
[    0.230016] cpuidle: using governor ladder                                   
[    0.260008] cpuidle: using governor menu                                     
[    0.265152] gpio-oxnas 44000000.gpio: at address c8848000                    
[    0.265576] gpio-oxnas 44100000.gpio: at address c884a000                    
[    0.266168] pinctrl-oxnas pinctrl: initialized OX820 pinctrl driver          
[    0.280634] vgaarb: loaded                                                   
[    0.281152] SCSI subsystem initialized                                       
[    0.282094] usbcore: registered new interface driver usbfs                   
[    0.282209] usbcore: registered new interface driver hub                     
[    0.282375] usbcore: registered new device driver usb                        
[    0.282572] pps_core: LinuxPPS API ver. 1 registered                         
[    0.282588] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giom>
[    0.282634] PTP clock support registered                                     
[    0.284887] clocksource: Switched to clocksource rps_clocksource_timer       
[    0.304916] NET: Registered protocol family 2                                
[    0.305848] TCP established hash table entries: 1024 (order: 0, 4096 bytes)  
[    0.305894] TCP bind hash table entries: 1024 (order: 1, 8192 bytes)         
[    0.305932] TCP: Hash tables configured (established 1024 bind 1024)         
[    0.306035] UDP hash table entries: 256 (order: 1, 8192 bytes)               
[    0.306078] UDP-Lite hash table entries: 256 (order: 1, 8192 bytes)          
[    0.306357] NET: Registered protocol family 1                                
[    0.306918] RPC: Registered named UNIX socket transport module.              
[    0.306941] RPC: Registered udp transport module.                            
[    0.306951] RPC: Registered tcp transport module.                            
[    0.306964] RPC: Registered tcp NFSv4.1 backchannel transport module.        
[    0.308902] Initialise system trusted keyring                                
[    0.323517] zbud: loaded                                                     
[    0.328988] NFS: Registering the id_resolver key type                        
[    0.329054] Key type id_resolver registered                                  
[    0.329067] Key type id_legacy registered                                    
[    0.329113] nfs4filelayout_init: NFSv4 File Layout Driver Registering...     
[    0.329136] Installing knfsd (copyright (C) 1996 okir@monad.swb.de).         
[    0.330273] fuse init (API version 7.23)                                     
[    0.332408] Key type big_key registered                                      
[    0.399618] Key type asymmetric registered                                   
[    0.399654] Asymmetric key parser 'x509' registered                          
[    0.399795] Block layer SCSI generic (bsg) driver version 0.4 loaded (major )
[    0.399823] io scheduler noop registered                                     
[    0.399846] io scheduler deadline registered                                 
[    0.399912] io scheduler cfq registered (default)                            
[    0.400808] Serial: 8250/16550 driver, 1 ports, IRQ sharing disabled         
[    0.402411] console [ttyS0] disabled                                         
[    0.402513] 44200000.uart: ttyS0 at MMIO 0x44200000 (irq = 6, base_baud = 39A
[    0.916549] console [ttyS0] enabled                                          
[    0.932318] loop: module loaded                                              
[    0.936934] nand: second ID read did not match 00,1d against ad,f1           
[    0.943147] nand: No NAND device found                                       
[    0.947468] oxnas-gmac 40400000.ethernet: no reset control found             
[    0.953518] stmmac - user ID: 0x12, Synopsys ID: 0x35                        
[    0.958545]  Ring mode enabled                                               
[    0.961605]  DMA HW capability register supported                            
[    0.966113]  Enhanced/Alternate descriptors                                  
[    0.970467]  Enabled extended descriptors                                    
[    0.974453]  RX Checksum Offload Engine supported (type 2)                   
[    0.979904]  TX Checksum insertion supported                                 
[    0.984166]  Wake-Up On Lan supported                                        
[    0.987809]  Enable RX Mitigation via HW Watchdog Timer                      
[    0.995566] libphy: stmmac: probed                                           
[    0.998963] eth%d: PHY ID 001cc914 at 0 IRQ POLL (stmmac-0:00) active        
[    1.005480] eth%d: PHY ID 001cc914 at 3 IRQ POLL (stmmac-0:03)               
[    1.012336] PPP generic driver version 2.4.2                                 
[    1.017249] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver       
[    1.023907] ehci-pci: EHCI PCI platform driver                               
[    1.028807] usbcore: registered new interface driver cdc_acm                 
[    1.034544] cdc_acm: USB Abstract Control Model driver for USB modems and ISs
[    1.042721] usbcore: registered new interface driver cdc_wdm                 
[    1.048522] usbcore: registered new interface driver usb-storage             
[    1.054743] usbcore: registered new interface driver usbserial               
[    1.060664] usbcore: registered new interface driver usbserial_generic       
[    1.067223] usbserial: USB Serial support registered for generic             
[    1.073687] mousedev: PS/2 mouse device common for all mice                  
[    1.079424] i2c /dev entries driver                                          
[    1.084431] hidraw: raw HID events driver (C) Jiri Kosina                    
[    1.090201] usbcore: registered new interface driver usbhid                  
[    1.095749] usbhid: USB HID core driver                                      
[    1.101552] NET: Registered protocol family 10                               
[    1.107450] sit: IPv6 over IPv4 tunneling driver                             
[    1.113164] NET: Registered protocol family 17                               
[    1.117785] Key type dns_resolver registered                                 
[    1.123509] registered taskstats version 1                                   
[    1.127628] Loading compiled-in X.509 certificates                           
[    1.132577] zswap: loaded using pool lzo/zbud                                
[    1.139095] Key type encrypted registered                                    
[    1.160550] UBI error: cannot open mtd data, error -2VFS: Cannot open root d6
[    1.173803] Please append a correct "root=" boot option; here are the availa:
[    1.182228] Kernel panic - not syncing: VFS: Unable to mount root fs on unkn)
[    1.190464] CPU: 0 PID: 1 Comm: swapper/0 Not tainted 4.4.54-oxnas-tld-1 #2  
[    1.197391] Hardware name: PLXTECH NAS782X SoC (Flattened Device Tree)       
[    1.203911] [<c0010c6c>] (unwind_backtrace) from [<c000cf8c>] (show_stack+0x)
[    1.211630] [<c000cf8c>] (show_stack) from [<c02cf840>] (dump_stack+0x84/0xa)
[    1.218828] [<c02cf840>] (dump_stack) from [<c0091c78>] (panic+0x8c/0x1ec)   
[    1.225679] [<c0091c78>] (panic) from [<c0829188>] (mount_block_root+0x1a0/0)
[    1.233131] [<c0829188>] (mount_block_root) from [<c0829338>] (mount_root+0x)
[    1.240929] [<c0829338>] (mount_root) from [<c0829494>] (prepare_namespace+0)
[    1.248901] [<c0829494>] (prepare_namespace) from [<c0828e04>] (kernel_init_)
[    1.257743] [<c0828e04>] (kernel_init_freeable) from [<c0616b2c>] (kernel_in)
[    1.265802] [<c0616b2c>] (kernel_init) from [<c000a508>] (ret_from_fork+0x14)
[    1.273344] CPU1: stopping                                                   
[    1.276049] CPU: 1 PID: 0 Comm: swapper/1 Not tainted 4.4.54-oxnas-tld-1 #2  
[    1.282974] Hardware name: PLXTECH NAS782X SoC (Flattened Device Tree)       
[    1.289487] [<c0010c6c>] (unwind_backtrace) from [<c000cf8c>] (show_stack+0x)
[    1.297205] [<c000cf8c>] (show_stack) from [<c02cf840>] (dump_stack+0x84/0xa)
[    1.304404] [<c02cf840>] (dump_stack) from [<c000fb84>] (handle_IPI+0x190/0x)
[    1.311769] [<c000fb84>] (handle_IPI) from [<c0009468>] (gic_handle_irq+0x74)
[    1.319306] [<c0009468>] (gic_handle_irq) from [<c000da20>] (__irq_svc+0x40/)
[    1.326750] Exception stack(0xc7869f88 to 0xc7869fd0)                        
[    1.331781] 9f80:                   00000001 00000000 00000000 00000000 0000c
[    1.339921] 9fa0: 00000000 c7869fe0 c085b2c4 c08686ac c7868000 00000000 00008
[    1.348060] 9fc0: c000af3c c000af40 60000013 ffffffff                        
[    1.353093] [<c000da20>] (__irq_svc) from [<c000af40>] (arch_cpu_idle+0x28/0)
[    1.360463] [<c000af40>] (arch_cpu_idle) from [<c004d348>] (cpu_startup_entr)
[    1.368693] [<c004d348>] (cpu_startup_entry) from [<60009508>] (0x60009508)  
[    1.375631] ---[ end Kernel panic - not syncing: VFS: Unable to mount root f)


Re: Problem booting Pogo Pro 4.4.38-oxnas-tld-5 with Intel SSDSC2BF180A4L
June 20, 2017 06:04PM
And this is the serial log with kernel 4.1.0

4488520 bytes read in 758 ms (5.6 MiB/s)                                        
7033 bytes read in 29 ms (236.3 KiB/s)                                          
magic:                  0xd00dfeed                                              
totalsize:              0x1b79 (7033)                                           
off_dt_struct:          0x38                                                    
off_dt_strings:         0x18d4                                                  
off_mem_rsvmap:         0x28                                                    
version:                17                                                      
last_comp_version:      16                                                      
boot_cpuid_phys:        0x0                                                     
size_dt_strings:        0x2a5                                                   
size_dt_struct:         0x189c                                                  
number mem_rsv:         0x0                                                     
                                                                                
## Booting kernel from Legacy Image at 60000000 ...                             
   Image Name:   Linux-4.1.0-oxnas-tld-1                                        
   Image Type:   ARM Linux Kernel Image (uncompressed)                          
   Data Size:    4488456 Bytes = 4.3 MiB                                        
   Load Address: 60008000                                                       
   Entry Point:  60008000                                                       
   Verifying Checksum ... OK                                                    
## Flattened Device Tree blob at 61000000                                       
   Booting using the fdt blob at 0x61000000                                     
   Loading Kernel Image ... OK                                                  
   Loading Device Tree to 67e26000, end 67e2ab78 ... OK                         
                                                                                
Starting kernel ...                                                             
                                                                                
Uncompressing Linux... done, booting the kernel.                                
[    0.000000] Booting Linux on physical CPU 0x0                                
[    0.000000] Linux version 4.1.0-oxnas-tld-1 (root@tldDebian) (gcc version 4.5
[    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 Pro                                      
[    0.000000] Memory policy: Data cache writealloc                             
[    0.000000] DT missing boot CPU MPIDR[23:0], fall back to default cpu_logicap
[    0.000000] Start Ox820 platform                                             
[    0.000000] PERCPU: Embedded 11 pages/cpu @c7edc000 s15456 r8192 d21408 u4506
[    0.000000] Built 1 zonelists in Zone order, mobility grouping on.  Total pa2
[    0.000000] Kernel command line: console=ttyS0,115200n8 root=/dev/sda2 rootf2
[    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: 120984K/131072K available (6235K kernel code, 275K 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 - 0xff000000   ( 872 MB)                
[    0.000000]     lowmem  : 0xc0000000 - 0xc8000000   ( 128 MB)                
[    0.000000]     modules : 0xbf000000 - 0xc0000000   (  16 MB)                
[    0.000000]       .text : 0xc0008000 - 0xc07edec8   (8088 kB)                
[    0.000000]       .init : 0xc07ee000 - 0xc0820000   ( 200 kB)                
[    0.000000]       .data : 0xc0820000 - 0xc0864fa0   ( 276 kB)                
[    0.000000]        .bss : 0xc0864fa0 - 0xc08a06a0   ( 238 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: 0xs
[    0.000010] sched_clock: 24 bits at 390kHz, resolution 2560ns, wraps every 2s
[    0.000176] Console: colour dummy device 80x30                               
[    0.000209] Calibrating delay loop... 319.48 BogoMIPS (lpj=1597440)          
[    0.050035] pid_max: default: 32768 minimum: 301                             
[    0.050191] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes)      
[    0.050209] Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes) 
[    0.051082] Initializing cgroup subsys net_cls                               
[    0.051148] CPU: Testing write buffer coherency: ok                          
[    0.051550] Setting up static identity map for 0x60008220 - 0x60008258       
[    0.170147] Brought up 2 CPUs                                                
[    0.170170] SMP: Total of 2 processors activated (638.97 BogoMIPS).          
[    0.171159] devtmpfs: initialized                                            
[    0.176163] clocksource jiffies: mask: 0xffffffff max_cycles: 0xffffffff, mas
[    0.176404] prandom: seed boundary self test passed                          
[    0.181086] prandom: 100 self tests passed                                   
[    0.181117] pinctrl core: initialized pinctrl subsystem                      
[    0.182031] NET: Registered protocol family 16                               
[    0.182599] DMA: preallocated 256 KiB pool for atomic coherent allocations   
[    0.210009] cpuidle: using governor ladder                                   
[    0.240005] cpuidle: using governor menu                                     
[    0.245035] gpio-oxnas 44000000.gpio: at address c8848000                    
[    0.245370] gpio-oxnas 44100000.gpio: at address c884a000                    
[    0.245959] pinctrl-oxnas pinctrl: initialized OX820 pinctrl driver          
[    0.259525] vgaarb: loaded                                                   
[    0.260103] SCSI subsystem initialized                                       
[    0.260986] usbcore: registered new interface driver usbfs                   
[    0.261096] usbcore: registered new interface driver hub                     
[    0.261253] usbcore: registered new device driver usb                        
[    0.261483] pps_core: LinuxPPS API ver. 1 registered                         
[    0.261498] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giom>
[    0.261547] PTP clock support registered                                     
[    0.263296] cfg80211: Calling CRDA to update world regulatory domain         
[    0.263828] Switched to clocksource rps_clocksource_timer                    
[    0.283379] NET: Registered protocol family 2                                
[    0.284367] TCP established hash table entries: 1024 (order: 0, 4096 bytes)  
[    0.284416] TCP bind hash table entries: 1024 (order: 1, 8192 bytes)         
[    0.284451] TCP: Hash tables configured (established 1024 bind 1024)         
[    0.284551] UDP hash table entries: 256 (order: 1, 8192 bytes)               
[    0.284597] UDP-Lite hash table entries: 256 (order: 1, 8192 bytes)          
[    0.284884] NET: Registered protocol family 1                                
[    0.285381] RPC: Registered named UNIX socket transport module.              
[    0.285401] RPC: Registered udp transport module.                            
[    0.285414] RPC: Registered tcp transport module.                            
[    0.285424] RPC: Registered tcp NFSv4.1 backchannel transport module.        
[    0.287226] futex hash table entries: 512 (order: 2, 16384 bytes)            
[    0.287306] Initialise system trusted keyring                                
[    0.300597] zpool: loaded                                                    
[    0.300620] zbud: loaded                                                     
[    0.306547] NFS: Registering the id_resolver key type                        
[    0.306611] Key type id_resolver registered                                  
[    0.306624] Key type id_legacy registered                                    
[    0.306667] nfs4filelayout_init: NFSv4 File Layout Driver Registering...     
[    0.306688] Installing knfsd (copyright (C) 1996 okir@monad.swb.de).         
[    0.307681] fuse init (API version 7.23)                                     
[    0.309396] Key type big_key registered                                      
[    0.315440] alg: No test for stdrng (krng)                                   
[    0.367068] Key type asymmetric registered                                   
[    0.367101] Asymmetric key parser 'x509' registered                          
[    0.367242] Block layer SCSI generic (bsg) driver version 0.4 loaded (major )
[    0.367272] io scheduler noop registered                                     
[    0.367293] io scheduler deadline registered                                 
[    0.367669] io scheduler cfq registered (default)                            
[    0.368407] Serial: 8250/16550 driver, 1 ports, IRQ sharing disabled         
[    0.369889] console [ttyS0] disabled                                         
[    0.370068] 44200000.uart: ttyS0 at MMIO 0x44200000 (irq = 6, base_baud = 39A
[    0.898452] console [ttyS0] enabled                                          
[    0.912619] loop: module loaded                                              
[    0.917754] scsi host0: sata_oxnas                                           
[    0.921684] ata1: SATA max UDMA/133 irq 7                                    
[    0.925941] ox820sata: reseting SATA core                                    
[    0.926886] nand: Could not find valid ONFI parameter page; aborting         
[    0.926929] nand: device found, Manufacturer ID: 0xad, Chip ID: 0xf1         
[    0.926937] nand: Hynix NAND 128MiB 3,3V 8-bit                               
[    0.926945] nand: 128 MiB, SLC, erase size: 128 KiB, page size: 2048, OOB si4
[    0.926963] Scanning device for bad blocks                                   
[    1.010165] 2 ofpart partitions found on MTD device 41000000.nand            
[    1.016245] Creating 2 MTD partitions on "41000000.nand":                    
[    1.021639] 0x000000000000-0x000000e00000 : "boot"                           
[    1.028111] 0x000000e00000-0x000008000000 : "data"                           
[    1.035476] stmmaceth 40400000.ethernet: no reset control found              
[    1.041410] stmmac - user ID: 0x12, Synopsys ID: 0x35                        
[    1.046433]  Ring mode enabled                                               
[    1.049466]  DMA HW capability register supported                            
[    1.053987]  Enhanced/Alternate descriptors                                  
[    1.058316]  Enabled extended descriptors                                    
[    1.062315]  RX Checksum Offload Engine supported (type 2)                   
[    1.067768]  TX Checksum insertion supported                                 
[    1.072023]  Wake-Up On Lan supported                                        
[    1.075660]  Enable RX Mitigation via HW Watchdog Timer                      
[    1.084664] libphy: stmmac: probed                                           
[    1.088058] eth0: PHY ID 001cc914 at 0 IRQ POLL (stmmac-0:00) active         
[    1.094530] eth0: PHY ID 001cc914 at 3 IRQ POLL (stmmac-0:03)                
[    1.100354] PPP generic driver version 2.4.2                                 
[    1.105251] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver       
[    1.111795] ehci-pci: EHCI PCI platform driver                               
[    1.116738] oxnas-ehci 40200100.ehci: EHCI Host Controller                   
[    1.122270] oxnas-ehci 40200100.ehci: new USB bus registered, assigned bus n1
[    1.130096] oxnas-ehci 40200100.ehci: irq 10, io mem 0x40200100              
[    1.150597] oxnas-ehci 40200100.ehci: USB 2.0 started, EHCI 1.00             
[    1.156894] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002    
[    1.163740] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber1
[    1.170951] usb usb1: Product: EHCI Host Controller                          
[    1.175805] usb usb1: Manufacturer: Linux 4.1.0-oxnas-tld-1 ehci_hcd         
[    1.182144] usb usb1: SerialNumber: 40200100.ehci                            
[    1.187824] hub 1-0:1.0: USB hub found                                       
[    1.191703] hub 1-0:1.0: 2 ports detected                                    
[    1.196697] usbcore: registered new interface driver cdc_acm                 
[    1.202416] cdc_acm: USB Abstract Control Model driver for USB modems and ISs
[    1.210583] usbcore: registered new interface driver cdc_wdm                 
[    1.216404] usbcore: registered new interface driver usb-storage             
[    1.222666] usbcore: registered new interface driver usbserial               
[    1.228556] usbcore: registered new interface driver usbserial_generic       
[    1.235153] usbserial: USB Serial support registered for generic             
[    1.241571] mousedev: PS/2 mouse device common for all mice                  
[    1.247296] i2c /dev entries driver                                          
[    1.252195] hidraw: raw HID events driver (C) Jiri Kosina                    
[    1.257827] usbcore: registered new interface driver usbhid                  
[    1.263462] usbhid: USB HID core driver                                      
[    1.269132] NET: Registered protocol family 10                               
[    1.275258] sit: IPv6 over IPv4 tunneling driver                             
[    1.281100] NET: Registered protocol family 17                               
[    1.285703] Key type dns_resolver registered                                 
[    1.291504] Loading compiled-in X.509 certificates                           
[    1.300643] Key type encrypted registered                                    
[    1.305459] ubi0: attaching mtd1                                             
[    1.461150] ubi0: scanning is finished                                       
[    1.470028] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 310)           
[    1.479467] ubi0 warning: print_rsvd_warning: cannot reserve enough PEBs for0
[    1.489999] ata1.00: ATA-9: 25000128-02, 505ABBF0, max UDMA/133              
[    1.495895] ata1.00: 62533296 sectors, multi 16: LBA48 NCQ (depth 0/32)      
[    1.504483] ubi0: attached mtd1 (name "data", size 114 MiB)                  
[    1.507499] ata1.00: configured for UDMA/133                                 
[    1.508049] scsi 0:0:0:0: Direct-Access     ATA      25000128-02      BBF0 P5
[    1.510174] sd 0:0:0:0: [sda] 62533296 512-byte logical blocks: (32.0 GB/29.)
[    1.510863] sd 0:0:0:0: [sda] Write Protect is off                           
[    1.511093] sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doeA
[    1.513387]  sda: sda1 sda2 sda3                                             
[    1.516259] sd 0:0:0:0: [sda] Attached SCSI disk                             
[    1.551598] usb 1-1: new high-speed USB device number 2 using oxnas-ehci     
[    1.558328] ubi0: PEB size: 131072 bytes (128 KiB), LEB size: 129024 bytes   
[    1.565301] ubi0: min./max. I/O unit sizes: 2048/2048, sub-page size 512     
[    1.571996] ubi0: VID header offset: 512 (aligned 512), data offset: 2048    
[    1.578744] ubi0: good PEBs: 912, bad PEBs: 0, corrupted PEBs: 0             
[    1.584757] ubi0: user volume: 1, internal volumes: 1, max. volumes count: 18
[    1.591959] ubi0: max/mean erase counter: 2/0, WL threshold: 4096, image seq0
[    1.600268] ubi0: available PEBs: 0, total reserved PEBs: 912, PEBs reserved9
[    1.609418] ubi0: background thread "ubi_bgt0d" started, PID 70              
[    1.623833] EXT4-fs (sda2): mounted filesystem with ordered data mode. Opts:)
[    1.631600] VFS: Mounted root (ext4 filesystem) readonly on device 8:2.      
[    1.640755] devtmpfs: mounted                                                
[    1.644172] Freeing unused kernel memory: 200K (c07ee000 - c0820000)         
[    1.712501] usb 1-1: New USB device found, idVendor=05e3, idProduct=0608     
[    1.719180] usb 1-1: New USB device strings: Mfr=0, Product=1, SerialNumber=0
[    1.726312] usb 1-1: Product: USB2.0 Hub                                     
[    1.731481] hub 1-1:1.0: USB hub found                                       
[    1.735623] hub 1-1:1.0: 4 ports detected                                    
Mount failed for selinuxfs on /sys/fs/selinux:  No such file or directory       
INIT: version 2.88 booting                                                      
[info] Using makefile-style concurrent boot in runlevel S.                      
[    2.021048] usb 1-1.3: new full-speed USB device number 3 using oxnas-ehci   
[    2.397104] usb 1-1.3: New USB device found, idVendor=041e, idProduct=3220   
[    2.407616] usb 1-1.3: New USB device strings: Mfr=1, Product=2, SerialNumbe3
[    2.427640] usb 1-1.3: Product: Sound Blaster Tactic(3D) Sigma               
[    2.435184] usb 1-1.3: Manufacturer: Creative Technology Ltd                 
[    2.442219] usb 1-1.3: SerialNumber: 00052984                                
[    2.512122] input: Creative Technology Ltd Sound Blaster Tactic(3D) Sigma as0
[    2.581596] hid-generic 0003:041E:3220.0001: input,hiddev0,hidraw0: USB HID 3
[....] Starting the hotplug events dispatcher: udevd[    2.672232] udevd[184]: 5
. ok                                                                            
[....] Synthesizing the initial hotplug events...[    3.470371] cfg80211: Callin
done.                                                                           
[....] Waiting for /dev to be fully populated...[    4.057011] usbcore: registeo
done.                                                                           
[    5.065912] EXT4-fs (sda2): re-mounted. Opts: (null)                         
[....] Checking root file system...fsck from util-linux 2.20.1                  
rootfs: clean, 61533/262144 files, 442368/1048576 blocks                        
done.                                                                           
[    5.280737] EXT4-fs (sda2): re-mounted. Opts: errors=remount-ro              
[....] Cleaning up temporary files... /tmp /lib/init/rw. ok                     
[....] Loading kernel modules...done.                                           
[    6.630092] cfg80211: Calling CRDA to update world regulatory domain         
[....] Activating lvm and md swap...[    9.166778] Adding 524284k swap on /swapS
done.                                                                           
[....] Checking file systems...fsck from util-linux 2.20.1                      
home: clean, 11/1692432 files, 117472/6763734 blocks                            
done.                                                                           
[....] Mounting local filesystems...[    9.790136] cfg80211: Calling CRDA to upn
done.                                                                           
[....] Activating swapfile swap...done.                                         
[....] Cleaning up temporary files.... ok                                       
[....] Setting kernel variables ...done.                                        
[   11.226908] random: dd urandom read with 24 bits of entropy available        
[....] Configuring network interfaces...Internet Systems Consortium DHCP Client2
Copyright 2004-2011 Internet Systems Consortium.                                
All rights reserved.                                                            
For info, please visit https://www.isc.org/software/dhcp/                       
                                                                                
Listening on LPF/eth0/74:f0:6d:6a:1d:17                                         
Sending on   LPF/eth0/74:f0:6d:6a:1d:17                                         
Sending on   Socket/fallback                                                    
DHCPREQUEST on eth0 to 255.255.255.255 port 67                                  
[   12.960023] cfg80211: Calling CRDA to update world regulatory domain         
[   16.120005] cfg80211: Calling CRDA to update world regulatory domain         
[   17.780736] stmmaceth 40400000.ethernet eth0: Link is Up - 1Gbps/Full - flowx
DHCPREQUEST on eth0 to 255.255.255.255 port 67                                  
DHCPACK from 192.168.0.254                                                      
bound to 192.168.0.66 -- renewal in 19204 seconds.                              
[   19.287306] cfg80211: Calling CRDA to update world regulatory domain         
done.                                                                           
[....] Starting rpcbind daemon.... ok                                           
[....] Starting NFS common utilities: statd idmapd. ok                          
[....] Cleaning up temporary files.... ok                                       
[....] Setting up ALSA...done.                                                  
INIT: Entering runlevel: 2                                                      
[info] Using makefile-style concurrent boot in runlevel 2.                      
[....] Starting NTP server: ntpd. ok                                            
[   22.450173] cfg80211: Calling CRDA to update world regulatory domain         
[....] Starting system message bus: dbus[....] Starting busybox' syslogd implem.
1681 (syslogd)                                                                  
. ok                                                                            
[....] Starting rpcbind daemon...[....] Already running.. ok                    
.                                                                               
[....] Starting NFS common utilities: statd idmapd. 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 busybox' klogd implementation : klogdStarting /sbin/klogd...    
1683 (klogd)                                                                    
. ok                                                                            
                                                                                
Debian GNU/Linux 8 debian ttyS0                                                 
                                                                                
debian login:
Re: Problem booting Pogo Pro 4.4.38-oxnas-tld-5 with Intel SSDSC2BF180A4L
June 20, 2017 06:12PM
schnee,

> ## Booting kernel from Legacy Image at 60000000 ..
> .
> Image Name:   Linux-4.4.54-oxnas-tld-1
> Image Type:   ARM Linux Kernel Image (uncompres
> sed)
> Data Size:    4621760 Bytes = 4.4 MiB
> Load Address: 60008000
> Entry Point:  60008000
> Verifying Checksum ... OK
> ## Flattened Device Tree blob at 61000000
> Booting using the fdt blob at 0x61000000
> Loading Kernel Image ... OK
> Loading Device Tree to 67e26000, end 67e2ab78 .


Is there a partiular reason why the uInitrd was not loaded?

> [    0.000000] Kernel command line: console=ttyS0,115200n8 root=/dev/sda2 rootf2


If you had use uInitrd then the bootargs here should be

console=ttyS0,115200n8 root=LABEL=rootfs

And the boot log showed that there was no SATA drive recognized! so the uInitrd issue is kind of moot! I think one way to know for sure is to boot with USB rootfs and see if the drive can be detected in Linux after boot.

-bodhi
===========================
Forum Wiki
bodhi's corner (buy bodhi a beer)
Re: Problem booting Pogo Pro 4.4.38-oxnas-tld-5 with Intel SSDSC2BF180A4L
June 21, 2017 02:24PM
Changes the uboot enviroment (yesterday i used a copy of a working box). Here is the boot result with 4.4.54 (4.4.38 has the same result):
U-Boot SPL 2013.10-ga72eb8f-dirty (Feb 19 2014 - 15:21:12)
  Boot device: SATA
Attempting to set PLLA to 800 MHz ...
  plla_ctrl0 : 0000030a
  plla_ctrl1 : 00400000
  plla_ctrl2 : 007f0068
  plla_ctrl3 : 00000193

PLLA Set
Bus 0: OK 
  Device 0: 
reading bootargs.bin
reading u-boot.img
reading u-boot.img


U-Boot 2015.10-tld-1 (Nov 21 2015 - 01:17:59 -0800)
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... ERROR: Invalid USB EP length (9)
ERROR: Invalid USB EP length (9)
ERROR: Invalid USB EP length (9)
ERROR: Invalid USB EP length (9)
ERROR: Invalid USB EP length (9)
3 USB Device(s) found
       scanning usb for storage devices... 0 Storage Device(s) found

Reset IDE: Bus 0: OK 
  Device 0: Model: 25000128-02  Firm: 505ABBF0 Ser#: DSHKSH124404005 
            Type: Hard Disk
            Supports 48-bit addressing
            Capacity: 30533.8 MB = 29.8 GB (62533296 x 512)

no USB devices available

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

Part    Start Sector    Num Sectors     UUID            Type
  1     2048            32768           00008000-01     83
  2     34816           8388608         00008000-02     83
  3     8423424         54109872        00008000-03     83
loading envs from ide 0 ...
reading /boot/uEnv.txt
** Unable to read file /boot/uEnv.txt **
4621824 bytes read in 830 ms (5.3 MiB/s)
3042611 bytes read in 553 ms (5.2 MiB/s)
7033 bytes read in 29 ms (236.3 KiB/s)
## Booting kernel from Legacy Image at 60500000 ...
   Image Name:   Linux-4.4.54-oxnas-tld-1
   Created:      1970-01-01   0:05:03 UTC
   Image Type:   ARM Linux Kernel Image (uncompressed)
   Data Size:    4621760 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.54-oxnas-tld-1
   Created:      1970-01-01   0:05:03 UTC
   Image Type:   ARM Linux RAMDisk Image (gzip compressed)
   Data Size:    3042547 Bytes = 2.9 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 67b44000, end 67e2acf3 ... OK
   Loading Device Tree to 67b3f000, end 67b43b78 ... OK

Starting kernel ...

Uncompressing Linux... done, booting the kernel.
[    0.000000] Booting Linux on physical CPU 0x0
[    0.000000] Linux version 4.4.54-oxnas-tld-1 (root@tldDebian) (gcc version 4.9.2 (Debian 4.9.2-10) ) 7
[    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 Pro
[    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 s17696 r8192 d23264 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: 117720K/131072K available (6410K kernel code, 288K 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 - 0xc0827e14   (8320 kB)
[    0.000000]       .init : 0xc0828000 - 0xc0860000   ( 224 kB)
[    0.000000]       .data : 0xc0860000 - 0xc08a8378   ( 289 kB)
[    0.000000]        .bss : 0xc08a8378 - 0xc08e6350   ( 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.000007] sched_clock: 24 bits at 390kHz, resolution 2560ns, wraps every 21474835200ns
[    0.000171] Console: colour dummy device 80x30
[    0.000207] 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.051092] Initializing cgroup subsys net_cls
[    0.051176] CPU: Testing write buffer coherency: ok
[    0.051622] Setting up static identity map for 0x60008220 - 0x60008258
[    0.190149] Brought up 2 CPUs
[    0.190172] SMP: Total of 2 processors activated (638.97 BogoMIPS).
[    0.191004] devtmpfs: initialized
[    0.196723] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275s
[    0.196771] futex hash table entries: 512 (order: 2, 16384 bytes)
[    0.197099] prandom: seed boundary self test passed
[    0.201789] prandom: 100 self tests passed
[    0.201817] pinctrl core: initialized pinctrl subsystem
[    0.202700] NET: Registered protocol family 16
[    0.203274] DMA: preallocated 256 KiB pool for atomic coherent allocations
[    0.230018] cpuidle: using governor ladder
[    0.260014] cpuidle: using governor menu
[    0.265244] gpio-oxnas 44000000.gpio: at address c8848000
[    0.265676] gpio-oxnas 44100000.gpio: at address c884a000
[    0.266288] pinctrl-oxnas pinctrl: initialized OX820 pinctrl driver
[    0.279823] vgaarb: loaded
[    0.280407] SCSI subsystem initialized
[    0.281338] usbcore: registered new interface driver usbfs
[    0.281448] usbcore: registered new interface driver hub
[    0.281612] usbcore: registered new device driver usb
[    0.281809] pps_core: LinuxPPS API ver. 1 registered
[    0.281825] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
[    0.281873] PTP clock support registered
[    0.284134] clocksource: Switched to clocksource rps_clocksource_timer
[    0.304186] NET: Registered protocol family 2
[    0.305111] TCP established hash table entries: 1024 (order: 0, 4096 bytes)
[    0.305157] TCP bind hash table entries: 1024 (order: 1, 8192 bytes)
[    0.305195] TCP: Hash tables configured (established 1024 bind 1024)
[    0.305292] UDP hash table entries: 256 (order: 1, 8192 bytes)
[    0.305338] UDP-Lite hash table entries: 256 (order: 1, 8192 bytes)
[    0.305615] NET: Registered protocol family 1
[    0.306173] RPC: Registered named UNIX socket transport module.
[    0.306193] RPC: Registered udp transport module.
[    0.306204] RPC: Registered tcp transport module.
[    0.306216] RPC: Registered tcp NFSv4.1 backchannel transport module.
[    0.306629] Unpacking initramfs...
[    0.636884] Freeing initrd memory: 2972K (c7b44000 - c7e2b000)
[    0.638451] Initialise system trusted keyring
[    0.653463] zbud: loaded
[    0.659164] NFS: Registering the id_resolver key type
[    0.659225] Key type id_resolver registered
[    0.659238] Key type id_legacy registered
[    0.659287] nfs4filelayout_init: NFSv4 File Layout Driver Registering...
[    0.659312] Installing knfsd (copyright (C) 1996 okir@monad.swb.de).
[    0.660408] fuse init (API version 7.23)
[    0.662538] Key type big_key registered
[    0.749184] Key type asymmetric registered
[    0.749214] Asymmetric key parser 'x509' registered
[    0.749350] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 252)
[    0.749378] io scheduler noop registered
[    0.749399] io scheduler deadline registered
[    0.749483] io scheduler cfq registered (default)
[    0.750336] Serial: 8250/16550 driver, 1 ports, IRQ sharing disabled
[    0.751915] console [ttyS0] disabled
[    0.752017] 44200000.uart: ttyS0 at MMIO 0x44200000 (irq = 6, base_baud = 390625) is a 16550A
[    1.273689] console [ttyS0] enabled
[    1.412426] loop: module loaded
[    1.417064] nand: No NAND device found
[    1.421445] oxnas-gmac 40400000.ethernet: no reset control found
[    1.427432] stmmac - user ID: 0x12, Synopsys ID: 0x35
[    1.432501]  Ring mode enabled
[    1.435540]  DMA HW capability register supported
[    1.440071]  Enhanced/Alternate descriptors
[    1.444408]  Enabled extended descriptors
[    1.448394]  RX Checksum Offload Engine supported (type 2)
[    1.453870]  TX Checksum insertion supported
[    1.458114]  Wake-Up On Lan supported
[    1.461775]  Enable RX Mitigation via HW Watchdog Timer
[    1.488133] libphy: stmmac: probed
[    1.491601] eth%d: PHY ID 001cc914 at 0 IRQ POLL (stmmac-0:00) active
[    1.498019] eth%d: PHY ID 001cc914 at 3 IRQ POLL (stmmac-0:03)
[    1.504837] PPP generic driver version 2.4.2
[    1.509719] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[    1.516303] ehci-pci: EHCI PCI platform driver
[    1.521315] usbcore: registered new interface driver cdc_acm
[    1.526947] cdc_acm: USB Abstract Control Model driver for USB modems and ISDN adapters
[    1.535080] usbcore: registered new interface driver cdc_wdm
[    1.540925] usbcore: registered new interface driver usb-storage
[    1.547087] usbcore: registered new interface driver usbserial
[    1.553036] usbcore: registered new interface driver usbserial_generic
[    1.559600] usbserial: USB Serial support registered for generic
[    1.566067] mousedev: PS/2 mouse device common for all mice
[    1.571860] i2c /dev entries driver
[    1.576750] hidraw: raw HID events driver (C) Jiri Kosina
[    1.582461] usbcore: registered new interface driver usbhid
[    1.588008] usbhid: USB HID core driver
[    1.593871] NET: Registered protocol family 10
[    1.610595] sit: IPv6 over IPv4 tunneling driver
[    1.616245] NET: Registered protocol family 17
[    1.620971] Key type dns_resolver registered
[    1.626575] registered taskstats version 1
[    1.630750] Loading compiled-in X.509 certificates
[    1.635660] zswap: loaded using pool lzo/zbud
[    1.660124] Key type encrypted registered
[    1.667317] Freeing unused kernel memory: 224K (c0828000 - c0860000)
Loading, please wait...
[    1.809461] udevd[92]: starting version 175
Begin: Loading essential drivers ... done.
Begin: Running /scripts/init-premount ... done.
Begin: Mounting root file system ... Begin: Running /scripts/local-top ... done.
Begin: Waiting for root file system ... done.
Gave up waiting for root device.  Common problems:
 - Boot args (cat /proc/cmdline)
   - Check rootdelay= (did the system wait long enough?)
   - Check root= (did the system wait for the right device?)
 - Missing modules (cat /proc/modules; ls /dev)
ALERT!  /dev/disk/by-label/rootfs does not exist.  Dropping to a shell!
modprobe: module i8042 not found in modules.dep
modprobe: module uhci-hcd not found in modules.dep
modprobe: module ohci-hcd not found in modules.dep


BusyBox v1.20.2 (Debian 1:1.20.0-7) built-in shell (ash)
Enter 'help' for a list of built-in commands.

/bin/sh: can't access tty; job control turned off
(initramfs) cat /proc6cmdline
cat: can't open '/proc6cmdline': No such file or directory
(initramfs) cat /proc/cmdline
console=ttyS0,115200 root=LABEL=rootfs rootdelay=10
(initramfs) ls /dev
block               ppp                 tty27               tty52
btrfs-control       ptmx                tty28               tty53
char                pts                 tty29               tty54
console             random              tty3                tty55
cpu_dma_latency     stderr              tty30               tty56
fd                  stdin               tty31               tty57
full                stdout              tty32               tty58
fuse                tty                 tty33               tty59
input               tty0                tty34               tty6
kmem                tty1                tty35               tty60
kmsg                tty10               tty36               tty61
loop-control        tty11               tty37               tty62
loop0               tty12               tty38               tty63
loop1               tty13               tty39               tty7
loop2               tty14               tty4                tty8
loop3               tty15               tty40               tty9
loop4               tty16               tty41               ttyS0
loop5               tty17               tty42               ubi_ctrl
loop6               tty18               tty43               urandom
loop7               tty19               tty44               vcs
mapper              tty2                tty45               vcs1
mem                 tty20               tty46               vcsa
memory_bandwidth    tty21               tty47               vcsa1
net                 tty22               tty48               vga_arbiter
network_latency     tty23               tty49               zero
network_throughput  tty24               tty5
null                tty25               tty50
port                tty26               tty51
(initramfs) 
Re: Problem booting Pogo Pro 4.4.38-oxnas-tld-5 with Intel SSDSC2BF180A4L
June 21, 2017 03:42PM
This is the same behavior as before. The SATA drive was not recognized at all.

You could try the same test like I mentioned: boot with USB rootfs, and see if the SSD is recognized in Linux. And then we go from there.

If the SSD is initialized and works normally in Linux, then go back to serial console, adjust the bootargs:

Remove:
rootdelay=10

Add:
rootwait

-bodhi
===========================
Forum Wiki
bodhi's corner (buy bodhi a beer)
Re: Problem booting Pogo Pro 4.4.38-oxnas-tld-5 with Intel SSDSC2BF180A4L
June 22, 2017 01:01AM
I put together a USB boot, and yes the drive recognized in Linux:
root@debian:/# uname -r
4.4.54-oxnas-tld-1

[   98.557393] sata_oxnas: resetting SATA core
[   98.571842] ata1: exception Emask 0x10 SAct 0x0 SErr 0x20000 action 0xe frozen
[   98.579038] ata1: SError: { PHYInt }
[   98.582661] ata1: hard resetting link
[   99.490158] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
[   99.497551] ata1.00: ATA-9: 25000128-02, 505ABBF0, max UDMA/133
[   99.503503] ata1.00: 62533296 sectors, multi 16: LBA48 NCQ (depth 0/32)
[   99.517550] ata1.00: configured for UDMA/133
[   99.521861] ata1: EH complete
[   99.540538] scsi 0:0:0:0: Direct-Access     ATA      25000128-02      BBF0 PQ: 0 ANSI: 5
[   99.550389] sd 0:0:0:0: [sdb] 62533296 512-byte logical blocks: (32.0 GB/29.8 GiB)
[   99.559639] sd 0:0:0:0: [sdb] Write Protect is off
[   99.564515] sd 0:0:0:0: [sdb] Mode Sense: 00 3a 00 00
[   99.564864] sd 0:0:0:0: [sdb] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
[   99.576990]  sdb: sdb1 sdb2 sdb3
[   99.584028] sd 0:0:0:0: [sdb] Attached SCSI disk

I can mount it and read write the files on it.

I am going to add rootwait to bootargs tonight and see what happens
Re: Problem booting Pogo Pro 4.4.38-oxnas-tld-5 with Intel SSDSC2BF180A4L
June 22, 2017 05:10PM
After reinstalling the drive with the root fs and completely redoing the uboot params, the Pogo boots from the 32G SSD with kernel 4.4.54. Here are the redone uboot envs:
U-Boot 2013.10-ga72eb8f-dirty (Feb 19 2014 - 15:21:12) for OXNAS

DRAM:  128 MiB
IDE:   Bus 0: OK 
  Device 0: Model: 25000128-02  Firm: 505ABBF0 Ser#: DSHKSH124404005 
            Type: Hard Disk
            Supports 48-bit addressing
            Capacity: 30533.8 MB = 29.8 GB (62533296 x 512)
NAND:  128 MiB
reading u-boot.env
In:    serial
Out:   serial
Err:   serial
Net:   mii0
Hit any key to stop autoboot:  0 
OX820 # printenv
autoload=no
baudrate=115200
bootcmd=run dt_bootcmd_ide;  reset
bootdelay=10
console=console=ttyS0,115200n8
dt_bootcmd_ide=ide info; run dt_ide_bootcmd; ide reset
dt_bootm=bootm $uimage_addr $uinitrd_addr $dtb_addr
dt_ide_boot=run dt_load_uimage; run dt_load_initrd; run dt_load_dtb; run dt_bootm
dt_ide_bootcmd=run ide_set_bootargs; run dt_ide_boot
dt_load_dtb=ext2load ide 0:2 $dtb_addr /boot/dts/ox820-pogoplug-classic.dtb
dt_load_initrd=ext2load ide 0:2 $uinitrd_addr /boot/uInitrd
dt_load_uimage=ext2load ide 0:2 $uimage_addr /boot/uImage
dtb_addr=0x62c00000
ethact=mii0
ethaddr=00:25:31:02:98:01
fdtaddr=61000000
ide_set_bootargs=setenv bootargs console=ttyS0,115200 root=LABEL=rootfs rootdelay=10
ipaddr=10.0.0.71
keypressed=1
mtdids=nand0=41000000.nand
mtdparts=mtdparts=41000000.nand:14m(boot),-(data)
netcon=echo switch to net console;setenv stderr nc;setenv stdin nc;setenv stdout nc;echo switch to net ce
serialcon=echo switch to serial console;setenv stderr serial;setenv stdin serial;setenv stdout serial;ece
stderr=serial
stdin=serial
stdout=serial
uimage_addr=0x60500000
uinitrd_addr=0x60e00000
updboot=bootp;tftp 60000000 u-boot.img;ide write 60000000 400 400

Environment size: 1266/16380 bytes
OX820 #

The SSD drive mentioned in the tittle of the thread is still unable to boot with kernel 4.4.54 even with the new uboot settings. And it is not possible to mount it if the system is booted from USB on 4.4.54:

[  115.515166] sata_oxnas: resetting SATA core
[  115.529784] ata1: exception Emask 0x10 SAct 0x0 SErr 0x20000 action 0xe frozen
[  115.537018] ata1: SError: { PHYInt }
[  115.540881] ata1: hard resetting link
[  116.450168] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
[  116.467281] ata1.00: ATA-9: INTEL SSDSC2BF180A4L, LADi, max UDMA/133
[  116.473656] ata1.00: 351651888 sectors, multi 16: LBA48 NCQ (depth 0/32)
root@debian:~# [  131.480176] ata1.00: qc timeout (cmd 0xef)
[  131.484257] sata_oxnas: resetting SATA core
[  131.840168] ata1.00: failed to set xfermode (err_mask=0x5)
[  131.845647] ata1: hard resetting link
[  132.350167] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
[  147.370178] ata1.00: qc timeout (cmd 0xef)
[  147.374259] sata_oxnas: resetting SATA core
[  147.730181] ata1.00: failed to set xfermode (err_mask=0x5)
[  147.735656] ata1.00: limiting speed to UDMA/133:PIO3
[  147.740904] ata1: hard resetting link
[  148.250173] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
[  163.270177] ata1.00: qc timeout (cmd 0xef)
[  163.274260] sata_oxnas: resetting SATA core
[  163.630177] ata1.00: failed to set xfermode (err_mask=0x5)
[  163.635648] ata1.00: disabled
[  163.638617] sata_oxnas: resetting SATA core
[  163.653376] ata1: exception Emask 0x10 SAct 0x0 SErr 0x20000 action 0xe frozen t4
[  163.660856] ata1: SError: { PHYInt }
[  163.664437] ata1: hard resetting link
[  164.570178] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
[  164.587292] ata1.00: ATA-9: INTEL SSDSC2BF180A4L, LADi, max UDMA/133
[  164.593664] ata1.00: 351651888 sectors, multi 16: LBA48 NCQ (depth 0/32)


Re: Problem booting Pogo Pro 4.4.38-oxnas-tld-5 with Intel SSDSC2BF180A4L
June 22, 2017 07:46PM
schnee,

The problem has nothing to do with u-boot.

As I mentioned, the mainline sata driver has changed quite a bit regarding Intel SSDs and other SSDs too. So to solve this, I suspect, a patch to the mainline driver is needed.

-bodhi
===========================
Forum Wiki
bodhi's corner (buy bodhi a beer)
Re: Problem booting Pogo Pro 4.4.38-oxnas-tld-5 with Intel SSDSC2BF180A4L
October 06, 2017 05:12AM
Hi Bodhi,

by any chance did you look into this issue while you were working on the new release (https://forum.doozan.com/read.php?2,16044,38109#msg-38109)

Thanks
Re: Problem booting Pogo Pro 4.4.38-oxnas-tld-5 with Intel SSDSC2BF180A4L
October 06, 2017 05:09PM
Hi schnee,

> by any chance did you look into this issue while
> you were working on the new release
> (https://forum.doozan.com/read.php?2,16044,38109#msg-38109)

I did, but could not find anything change that could possible related to this specific Intel SSD model. The changes in libata-core.c mostly for a generic SATA drive. But those might have affected this drive, indirectly. Unfortunately, we don't really have a tech specs for this drive so hard to tell.

One thing that stood out in the changes from 4.0 to the latest driver was the reorganization of the list of Intel drives. But that code was not related the timing error.

Quote
first post
the error is around the sata as i see some timeouts in the log:
[ 3.070003] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
[ 3.087119] ata1.00: ATA-9: INTEL SSDSC2BF180A4L, LADi, max UDMA/133
[ 3.093496] ata1.00: 351651888 sectors, multi 16: LBA48 NCQ (depth 0/32)
[ 18.100001] ata1.00: qc timeout (cmd 0xef)
[ 18.104084] sata_oxnas: resetting SATA core
[ 18.460001] ata1.00: failed to set xfermode (err_mask=0x5)
[ 18.969999] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
[ 33.990005] ata1.00: qc timeout (cmd 0xef)

However, I have not spent a lot of time to investigate this :) so I might have missed some details. It's still on my list of unsolved todos for this OXNAS box, so when I build the next version I will revisit it.

Do you have a GoFlex Net/GoFlex Home/Pogo V4? I wonder how this SSD behaves in these small plugs when you plug in the drive directly to the SATA slot. It might be a better verification than the laptop or other boxes that you have tried.

-bodhi
===========================
Forum Wiki
bodhi's corner (buy bodhi a beer)
Re: Problem booting Pogo Pro 4.4.38-oxnas-tld-5 with Intel SSDSC2BF180A4L
October 14, 2017 02:36AM
bodhi Wrote:
-------------------------------------------------------

> Do you have a GoFlex Net/GoFlex Home/Pogo V4? I
> wonder how this SSD behaves in these small plugs
> when you plug in the drive directly to the SATA
> slot. It might be a better verification than the
> laptop or other boxes that you have tried.

I do not have any of those devices unfortunatelly. I will trry to get one for testing. Is it enough to test with the original firmware on those devices or something special needed?

schnee
Author:

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: