BUG report: Net console for 2015.10 U-Boot for Pogoplug V3 (OXNAS OX820) not working

Followed this thread http://forum.doozan.com/read.php?3,16017 to update my pogoplug pro's uboot.

The Uboot I used for my pogoplug Pro: uboot.2015.10-tld-1.ox820.bodhi.tar

Followed the flashing Instruction from 1 to 16 (just changed IP in step 12) on two Pogoplug-Pro, both can boot into debian rootfs usb fine.both had no net console output. It seems there's something broken in uboot. The preboot did not execute?

If I put "run preboot" or something like that to the bootcmd line

bootcmd=run preboot; run bootcmd_uenv; run scan_disk; run set_bootargs; run bootcmd_exec; reset


I can get some output from net console, but no use. I can't stop the booting procedure in this way.

Net console is not supposed to work this way...

However, I have serial cable and serial worked fine for the uboot.


serverip / router etc has no problem. I can get the nc output infomation from my kirkwood boxes in the same machine, also, if I changed uboot / enviroment for pogoplug pro to an old version (uboot.2013.10-tld-4.ox820.bodhi.tar --this file is pretty difficult to find now.) with this instruction http://blog.qnology.com/2015/04/hacking-pogoplug-v3oxnas-proclassic.html, the net console worked.

Thank you guys for your time.

chessplayer in http://forum.doozan.com/read.php?3,16017,page=9

also reported the net console problem of the latest uboot for
OXNAS OX820

David Dyer,

Interrupt serial console and

printenv

Please post the entire serial console log up until this point.

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

Wait till me flash it back to the bad version and hook the serial cable.

Flashing the latest uboot / enviroment procedure (Under debian)


root@PogoPro:~/uboot# flash_erase /dev/mtd0 0x0 6
Erasing 128 Kibyte @ a0000 -- 100 % complete
root@PogoPro:~/uboot# nandwrite /dev/mtd0 uboot.spl.2013.10.ox820.850mhz.mtd0.img
Writing data to block 0 at offset 0x0
root@PogoPro:~/uboot# nandwrite -s 262144 /dev/mtd0 uboot.2015.10-tld-1.ox820.mtd0.img
Writing data to block 2 at offset 0x40000
Writing data to block 3 at offset 0x60000
Writing data to block 4 at offset 0x80000
Writing data to block 5 at offset 0xa0000
root@PogoPro:~/uboot# flash_erase /dev/mtd0 0x00100000 1
Erasing 128 Kibyte @ 100000 -- 100 % complete
root@PogoPro:~/uboot# nandwrite -s 1048576 /dev/mtd0 uboot.2013.10-tld-5.ox820.environment.img
Writing data to block 8 at offset 0x100000
root@PogoPro:~/uboot# fw_setenv 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'
root@PogoPro:~/uboot# fw_setenv preboot 'run preboot_nc'
root@PogoPro:~/uboot# fw_setenv ipaddr '192.168.7.18'
root@PogoPro:~/uboot# fw_setenv serverip '192.168.7.4'
root@PogoPro:~/uboot# fw_printenv ethaddr
ethaddr=52:3b:20:9c:11:51
root@PogoPro:~/uboot# fw_setenv ethaddr '00:25:31:01:ca:aa'
root@PogoPro:~/uboot# fw_printenv ethaddr
ethaddr=00:25:31:01:ca:aa



Now will shutdown, open the box and hook the cable.

root@PogoPro:~/uboot# shutdown -h now

Broadcast message from root@PogoPro (pts/0) (Tue Jan 17 18:34:11 2017):

The system is going down for system halt NOW!

Serial console output:

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-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
OX820> printenv
bootcmd=run bootcmd_uenv; run scan_disk; run set_bootargs; run bootcmd_exec; reset
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_uenv=run uenv_load; if test $uenv_loaded -eq 1; then run uenv_import; fi
bootdelay=10
bootdev=usb
device=0:1
devices=usb ide
disks=0 1 2 3
dtb_file=/boot/dts/ox820-pogoplug-pro.dtb
ethact=dwmac.40400000
ethaddr=00:25:31:01:ca:aa
if_netconsole=ping $serverip
ipaddr=192.168.7.18
load_dtb=echo loading DTB $dtb_file ...; ext2load $bootdev $device 0x62c00000 $dtb_file
load_initrd=echo loading uInitrd ...; ext2load $bootdev $device 0x60e00000 /boot/uInitrd
load_uimage=echo loading uImage ...; ext2load $bootdev $device 0x60500000 /boot/uImage
mainlineLinux=yes
mtdids=nand0=41000000.nand
mtdparts=mtdparts=41000000.nand:14m(boot),-(data)
preboot=run preboot_nc
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 ext2load $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
serverip=192.168.7.4
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 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
usb_custom_params=zswap.enabled=1
usb_ready_retry=15

Environment size: 2747/131068 bytes
OX820>run bootcmd

Debian booted ok.



Edited 1 time(s). Last edit at 01/17/2017 10:38PM by bodhi.

Meanwhile, the net console had no info.

C:\Documents and Settings\Home\Desktop\ncat-portable-5.59BETA1>ncat -l -u -p 6666

During one of the booting with serial console, I noticed that uboot reported ping to server error (5 times), so it looks like this:

network is still not up at the beginning, ping error, no net console. I think someone else mentioned this in the thread also.

If I put "run start_netconsole" at the beginning of bootcmd, system can't boot, I guess it tries to start net console but ping fails.

If I put "run preboot" at the beginning of bootcmd, system can boot and we have some interesting errors which indicate the problem

Here is the log.=====at the time it begins to boot, network in uboot is still not up.

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-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: 10  0 
OX820> printenv bootcmd
bootcmd=run start_netconsole; run bootcmd_uenv; run scan_disk; run set_bootargs; run bootcmd_exec; reset
OX820> setenv bootcmd 'run boot    preboot; run bootcmd_uenv; run scan_disk; run set_bootargs; run bootcmd_exec; reset'
OX820> printenv bootcmd
bootcmd=run preboot; run bootcmd_uenv; run scan_disk; run set_bootargs; run bootcmd_exec; reset
OX820> saveenv
Saving Environment to NAND...
Erasing NAND...

Erasing at 0x100000 --  50% complete.
Erasing at 0x120000 -- 100% complete.
Writing to NAND... OK
OX820> run bootcmd
dwmac.40400000 Waiting for PHY auto negotiation to complete......... TIMEOUT !
dwmac.40400000: No link.
ping failed; host 192.168.7.4 is not alive
dwmac.40400000 Waiting for PHY auto negotiation to complete......... TIMEOUT !
dwmac.40400000: No link.
ping failed; host 192.168.7.4 is not alive
dwmac.40400000 Waiting for PHY auto negotiation to complete......... TIMEOUT !
dwmac.40400000: No link.
ping failed; host 192.168.7.4 is not alive
dwmac.40400000 Waiting for PHY auto negotiation to complete......... TIMEOUT !
dwmac.40400000: No link.
ping failed; host 192.168.7.4 is not alive
dwmac.40400000 Waiting for PHY auto negotiation to complete......... TIMEOUT !
dwmac.40400000: No link.
ping failed; host 192.168.7.4 is not alive
starting USB...
USB0:   USB EHCI 1.00
scanning bus 0 for devices... 3 USB Device(s) found
       scanning usb for storage devices... 1 Storage Device(s) found

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

Debian booted OK.


-------

Mod edit: please code tags.



Edited 1 time(s). Last edit at 01/17/2017 10:39PM by bodhi.

During the tests above, I still didn't get any info from net console. It looks like put "run preboot" in etc doesn't guarantee you can have output in net console

192.168.7.4 is my "server" and it runs 24/7 with UPS. it is always UP.

PogoPro login: root
Password:
Last login: Tue Jan 17 19:01:53 PST 2017 on ttyS0
Linux PogoPro 3.18.5-oxnas-tld-1 #2 SMP PREEMPT Thu Feb 12 11:17:40 PST 2015 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@PogoPro:~# ping 192.168.7.4
PING 192.168.7.4 (192.168.7.4) 56(84) bytes of data.
64 bytes from 192.168.7.4: icmp_req=1 ttl=128 time=24.0 ms
64 bytes from 192.168.7.4: icmp_req=2 ttl=128 time=1.35 ms
64 bytes from 192.168.7.4: icmp_req=3 ttl=128 time=1.49 ms
64 bytes from 192.168.7.4: icmp_req=4 ttl=128 time=1.31 ms
^C
--- 192.168.7.4 ping statistics ---
4 packets transmitted, 4 received, 0% packet loss, time 3030ms
rtt min/avg/max/mdev = 1.318/7.049/24.031/9.804 ms

Please use code tags to post logs.

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

David Dyer Wrote:
-------------------------------------------------------
> If I put "run start_netconsole" at the beginning
> of bootcmd, system can't boot, I guess it tries
> to start net console but ping fails.
>
> If I put "run preboot" at the beginning of
> bootcmd, system can boot and we have some
> interesting errors which indicate the problem

preboot is executed automatically, dont use "run preboot".

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

David,

Don't try to execute anything directly.

On your netconsole server 192.168.7.4

ncat -lup 6666 192.168.7.18 6666

And power up the Pogo V3, with serial console attached, But tell me where is the serial console converter is plugged into? is it a different PC than the 192.168.7.4 ? If it is the same one, you need open 2 terminal sessions, one for serial console and one for netconsole.

When you see the count down on serial console

ping 192.168.0.4
printenv
boot

If netconsole is running, the serial console output will stop. And the netconsole output will start. Try interrupt netconsole.

And report the result of the test above with both logs (serial console and netconsole).

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

Hi, Bodhi,

I just registered an account here.

First, I'd like to say I'm terribly sorry---- I made a very low level mistake.... I inserted the wrong network cable, a non-connected cable to the PogoPro after I opened the box and connected the cables... so please delete these posts---these posts are giving misleading info.


http://forum.doozan.com/read.php?3,32079,32088#msg-32088
January 17, 2017 10:11PM

http://forum.doozan.com/read.php?3,32079,32089#msg-32089
January 17, 2017 10:25PM

http://forum.doozan.com/read.php?3,32079,32090#msg-32090
January 17, 2017 10:27PM

I will run the tests you asked. I made a little change. The original 192.168.7.4 was a Windows XP machine without monitor, my putty for ssh / serial console is running on another Windows 7 machine. Now I changed the netcat server to one of my Kirkwood box (Machine name BackUpServer, IP 192.168.7.17) and run three putty from the Windows 7 machine. One putty for SSH to Dockstar (net console) which runs netcat, one putty for SSH connect to PogoPro, one putty for serial console. I changed the serverip in PogoPro uboot environment. Here is a screen shot.

Serial console, stop uboot, run ping / printenv / boot command under OX820> prompt

result:

1 ping is alive
2 printenv info attached.
3 boot ok.
4 NO netconsole output.


This is the output from serial console. There's no output from net console.

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-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
OX820> ping 192.168.7.17
Speed: 1000, full duplex
Using dwmac.40400000 device
host 192.168.7.17 is alive
OX820> printenv
bootcmd=run bootcmd_uenv; run scan_disk; run set_bootargs; run bootcmd_exec; reset
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_uenv=run uenv_load; if test $uenv_loaded -eq 1; then run uenv_import; fi
bootdelay=10
bootdev=usb
device=0:1
devices=usb ide
disks=0 1 2 3
dtb_file=/boot/dts/ox820-pogoplug-pro.dtb
ethact=dwmac.40400000
ethaddr=00:25:31:01:ca:aa
if_netconsole=ping $serverip
ipaddr=192.168.7.18
load_dtb=echo loading DTB $dtb_file ...; ext2load $bootdev $device 0x62c00000 $dtb_file
load_initrd=echo loading uInitrd ...; ext2load $bootdev $device 0x60e00000 /boot/uInitrd
load_uimage=echo loading uImage ...; ext2load $bootdev $device 0x60500000 /boot/uImage
mainlineLinux=yes
mtdids=nand0=41000000.nand
mtdparts=mtdparts=41000000.nand:14m(boot),-(data)
preboot=run preboot_nc
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 ext2load $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
serverip=192.168.7.17
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 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
usb_custom_params=zswap.enabled=1
usb_ready_retry=15

Environment size: 2748/131068 bytes
OX820> boot
starting USB...
USB0:   USB EHCI 1.00
scanning bus 0 for devices... 3 USB Device(s) found
       scanning usb for storage devices... 1 Storage Device(s) found

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     51              7335789         00000000-01     83 Boot
  2     7335840         485112          00000000-02     82
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
1 bytes read in 210 ms (0 Bytes/s)
Found bootable drive on usb 0
loading uImage ...
4170064 bytes read in 458 ms (8.7 MiB/s)
loading uInitrd ...
2245099 bytes read in 404 ms (5.3 MiB/s)
loading DTB /boot/dts/ox820-pogoplug-pro.dtb ...
7029 bytes read in 237 ms (28.3 KiB/s)
## Booting kernel from Legacy Image at 60500000 ...
   Image Name:   Linux-3.18.5-oxnas-tld-1
   Created:      2015-10-23   2:26:57 UTC
   Image Type:   ARM Linux Kernel Image (uncompressed)
   Data Size:    4170000 Bytes = 4 MiB
   Load Address: 60008000
   Entry Point:  60008000
   Verifying Checksum ... OK
## Loading init Ramdisk from Legacy Image at 60e00000 ...
   Image Name:   initramfs-3.18.5-oxnas-tld-1
   Created:      2015-10-23   2:29:24 UTC
   Image Type:   ARM Linux RAMDisk Image (gzip compressed)
   Data Size:    2245035 Bytes = 2.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 67c07000, end 67e2b1ab ... OK
   Loading Device Tree to 67c02000, end 67c06b74 ... OK

Starting kernel ...

Uncompressing Linux... done, booting the kernel.
[    0.000000] Booting Linux on physical CPU 0x0
[    0.000000] Linux version 3.18.5-oxnas-tld-1 (root@tldDebian) (gcc version 4.6.3 (Debian 4.6.3-14) ) #2 SMP PREEMPT Thu Feb 12 11:17:40 PST 2015
[    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] Start Ox820 platform
[    0.000000] PERCPU: Embedded 9 pages/cpu @c7ee1000 s6624 r8192 d22048 u36864
[    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: 119396K/131072K available (5789K kernel code, 294K rwdata, 1704K rodata, 184K init, 236K bss, 11676K reserved)
[    0.000000] Virtual kernel memory layout:
[    0.000000]     vector  : 0xffff0000 - 0xffff1000   (   4 kB)
[    0.000000]     fixmap  : 0xffc00000 - 0xffe00000   (2048 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 - 0xc0759918   (7495 kB)
[    0.000000]       .init : 0xc075a000 - 0xc0788000   ( 184 kB)
[    0.000000]       .data : 0xc0788000 - 0xc07d1800   ( 294 kB)
[    0.000000]        .bss : 0xc07d1800 - 0xc080cab8   ( 237 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.000017] sched_clock: 24 bits at 390kHz, resolution 2560ns, wraps every 42949670400ns
[    0.000181] Console: colour dummy device 80x30
[    0.000209] Calibrating delay loop... 339.14 BogoMIPS (lpj=1695744)
[    0.050055] pid_max: default: 32768 minimum: 301
[    0.050222] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes)
[    0.050242] Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes)
[    0.051107] Initializing cgroup subsys net_cls
[    0.051169] CPU: Testing write buffer coherency: ok
[    0.051571] Setting up static identity map for 0x60580b78 - 0x60580bb0
[    0.110489] CPU1: Booted secondary processor
[    0.170160] Brought up 2 CPUs
[    0.170186] SMP: Total of 2 processors activated (678.29 BogoMIPS).
[    0.171097] devtmpfs: initialized
[    0.174144] prandom: seed boundary self test passed
[    0.178063] prandom: 100 self tests passed
[    0.178083] pinctrl core: initialized pinctrl subsystem
[    0.180902] NET: Registered protocol family 16
[    0.181440] DMA: preallocated 256 KiB pool for atomic coherent allocations
[    0.185751] gpio-oxnas 44000000.gpio: at address c8848000
[    0.186040] gpio-oxnas 44100000.gpio: at address c884a000
[    0.186590] pinctrl-oxnas pinctrl: initialized OX820 pinctrl driver
[    0.307947] oxnas-pcie 47c0: PCIe version/deviceID 0x82510b5
[    0.308029] oxnas-pcie 47c0: link up
[    0.308249] oxnas-pcie 47c0: PCI host bridge to bus 0000:00
[    0.308275] pci_bus 0000:00: root bus resource [mem 0x48000000-0x49ffffff]
[    0.308295] pci_bus 0000:00: root bus resource [mem 0x4a000000-0x4bdfffff pref]
[    0.308311] pci_bus 0000:00: root bus resource [io  0x0000-0xfffff]
[    0.308331] pci_bus 0000:00: root bus resource [bus 00-7f]
[    0.308771] PCI: bus0: Fast back to back transfers disabled
[    0.308851] pci 0000:00:00.0: BAR 0: assigned [mem 0x48000000-0x4800ffff]
[    0.309232] vgaarb: loaded
[    0.309734] SCSI subsystem initialized
[    0.310668] usbcore: registered new interface driver usbfs
[    0.310804] usbcore: registered new interface driver hub
[    0.310960] usbcore: registered new device driver usb
[    0.311101] pps_core: LinuxPPS API ver. 1 registered
[    0.311114] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
[    0.311162] PTP clock support registered
[    0.312609] cfg80211: Calling CRDA to update world regulatory domain
[    0.313090] Switched to clocksource rps_clocksource_timer
[    0.327242] NET: Registered protocol family 2
[    0.328199] TCP established hash table entries: 1024 (order: 0, 4096 bytes)
[    0.328243] TCP bind hash table entries: 1024 (order: 1, 8192 bytes)
[    0.328279] TCP: Hash tables configured (established 1024 bind 1024)
[    0.328360] TCP: reno registered
[    0.328389] UDP hash table entries: 256 (order: 1, 8192 bytes)
[    0.328430] UDP-Lite hash table entries: 256 (order: 1, 8192 bytes)
[    0.328727] NET: Registered protocol family 1
[    0.329203] RPC: Registered named UNIX socket transport module.
[    0.329223] RPC: Registered udp transport module.
[    0.329233] RPC: Registered tcp transport module.
[    0.329244] RPC: Registered tcp NFSv4.1 backchannel transport module.
[    0.329623] Unpacking initramfs...
[    0.560688] Freeing initrd memory: 2196K (c7c07000 - c7e2c000)
[    0.561802] futex hash table entries: 512 (order: 2, 16384 bytes)
[    0.561881] Initialise system trusted keyring
[    0.580213] NFS: Registering the id_resolver key type
[    0.580270] Key type id_resolver registered
[    0.580282] Key type id_legacy registered
[    0.580323] nfs4filelayout_init: NFSv4 File Layout Driver Registering...
[    0.580341] Installing knfsd (copyright (C) 1996 okir@monad.swb.de).
[    0.581291] fuse init (API version 7.23)
[    0.582732] msgmni has been set to 237
[    0.583272] Key type big_key registered
[    0.587077] alg: No test for stdrng (krng)
[    0.653068] Key type asymmetric registered
[    0.653099] Asymmetric key parser 'x509' registered
[    0.653240] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 252)
[    0.653271] io scheduler noop registered
[    0.653294] io scheduler deadline registered
[    0.653649] io scheduler cfq registered (default)
[    0.654164] Serial: 8250/16550 driver, 1 ports, IRQ sharing disabled
[    0.655472] console [ttyS0] disabled
[    0.655603] 44200000.uart: ttyS0 at MMIO 0x44200000 (irq = 55, base_baud = 390625) is a 16550A
[    1.224768] console [ttyS0] enabled
[    1.233909] loop: module loaded
[    1.238338] scsi host0: sata_oxnas
[    1.242401] ata1: SATA max UDMA/133 irq 50
[    1.246737] ox820sata: reseting SATA core
[    1.247895] nand: Could not find valid ONFI parameter page; aborting
[    1.247938] nand: device found, Manufacturer ID: 0xad, Chip ID: 0xf1
[    1.247943] nand: Hynix NAND 128MiB 3,3V 8-bit
[    1.247951] nand: 128MiB, SLC, page size: 2048, OOB size: 64
[    1.247966] Scanning device for bad blocks
[    1.309376] Bad eraseblock 769 at 0x000006020000
[    1.334243] 2 cmdlinepart partitions found on MTD device 41000000.nand
[    1.340769] Creating 2 MTD partitions on "41000000.nand":
[    1.346147] 0x000000000000-0x000000e00000 : "boot"
[    1.352391] 0x000000e00000-0x000008000000 : "data"
[    1.359144] stmmaceth 40400000.ethernet: no reset control found
[    1.365076] stmmac - user ID: 0x12, Synopsys ID: 0x35
[    1.370096]  Ring mode enabled
[    1.373145]  DMA HW capability register supported
[    1.377648]  Enhanced/Alternate descriptors
[    1.381990]  Enabled extended descriptors
[    1.385973]  RX Checksum Offload Engine supported (type 2)
[    1.391439]  TX Checksum insertion supported
[    1.395681]  Wake-Up On Lan supported
[    1.399319]  Enable RX Mitigation via HW Watchdog Timer
[    1.425745] libphy: stmmac: probed
[    1.429143] eth0: PHY ID 001cc914 at 0 IRQ POLL (stmmac-0:00) active
[    1.435517] eth0: PHY ID 001cc914 at 3 IRQ POLL (stmmac-0:03)
[    1.441405] PPP generic driver version 2.4.2
[    1.446018] rt2800pci 0000:00:00.0: enabling device (0140 -> 0142)
[    1.452387] ieee80211 phy0: rt2x00_set_rt: Info - RT chipset 3090, rev 3213 detected
[    1.463557] ieee80211 phy0: rt2x00_set_rf: Info - RF chipset 0005 detected
[    1.472486] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[    1.478999] ehci-pci: EHCI PCI platform driver
[    1.483816] oxnas-ehci 40200100.ehci: EHCI Host Controller
[    1.489320] oxnas-ehci 40200100.ehci: new USB bus registered, assigned bus number 1
[    1.497118] oxnas-ehci 40200100.ehci: irq 39, io mem 0x40200100
[    1.520593] oxnas-ehci 40200100.ehci: USB 2.0 started, EHCI 1.00
[    1.526865] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002
[    1.533657] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    1.540871] usb usb1: Product: EHCI Host Controller
[    1.545728] usb usb1: Manufacturer: Linux 3.18.5-oxnas-tld-1 ehci_hcd
[    1.552156] usb usb1: SerialNumber: 40200100.ehci
[    1.557685] hub 1-0:1.0: USB hub found
[    1.561530] hub 1-0:1.0: 2 ports detected
[    1.566144] usbcore: registered new interface driver cdc_acm
[    1.571809] cdc_acm: USB Abstract Control Model driver for USB modems and ISDN adapters
[    1.579873] usbcore: registered new interface driver cdc_wdm
[    1.585692] usbcore: registered new interface driver usb-storage
[    1.591933] usbcore: registered new interface driver usbserial
[    1.597803] usbcore: registered new interface driver usbserial_generic
[    1.604392] usbserial: USB Serial support registered for generic
[    1.610728] mousedev: PS/2 mouse device common for all mice
[    1.616294] i2c /dev entries driver
[    1.620938] usbcore: registered new interface driver usbhid
[    1.626485] usbhid: USB HID core driver
[    1.630036] ata1: SATA link down (SStatus 0 SControl 310)
[    1.636006] TCP: cubic registered
[    1.640384] NET: Registered protocol family 10
[    1.646566] sit: IPv6 over IPv4 tunneling driver
[    1.652190] NET: Registered protocol family 17
[    1.656765] Key type dns_resolver registered
[    1.662154] Loading compiled-in X.509 certificates
[    1.670814] Freeing unused kernel memory: 184K (c075a000 - c0788000)
Loading, please wait...
[    1.807354] udevd[67]: starting version 175
[    1.880770] usb 1-1: new high-speed USB device number 2 using oxnas-ehci
[    2.043279] usb 1-1: New USB device found, idVendor=05e3, idProduct=0608
[    2.049966] usb 1-1: New USB device strings: Mfr=0, Product=1, SerialNumber=0
[    2.080688] usb 1-1: Product: USB2.0 Hub
[    2.101235] hub 1-1:1.0: USB hub found
[    2.140129] hub 1-1:1.0: 4 ports detected
[    2.440936] usb 1-1.1: new high-speed USB device number 3 using oxnas-ehci
[    2.551674] usb 1-1.1: New USB device found, idVendor=0781, idProduct=5573
[    2.558528] usb 1-1.1: New USB device strings: Mfr=1, Product=2, SerialNumber=3
[    2.565829] usb 1-1.1: Product: Staples
[    2.569648] usb 1-1.1: Manufacturer:
[    2.575984] usb 1-1.1: SerialNumber: 4C532000011009101052
[    2.583864] usb-storage 1-1.1:1.0: USB Mass Storage device detected
[    2.590650] scsi host1: usb-storage 1-1.1:1.0
[    2.610388] usbcore: registered new interface driver uas
[    3.591528] scsi 1:0:0:0: Direct-Access     Staples                   1.22 PQ: 0 ANSI: 5
[    3.605544] sd 1:0:0:0: [sda] 7821312 512-byte logical blocks: (4.00 GB/3.72 GiB)
[    3.614750] sd 1:0:0:0: [sda] Write Protect is off
[    3.620707] sd 1:0:0:0: [sda] Write cache: disabled, read cache: enabled, doesn't support DPO or FUA
[    3.638607]  sda: sda1 sda2
[    3.646028] sd 1:0:0:0: [sda] Attached SCSI removable disk
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: Checking root file system ... fsck from util-linux 2.20.1
rootfs: clean, 26687/229376 files, 218516/916973 blocks
done.
[   12.684154] kjournald starting.  Commit interval 5 seconds
[   12.684229] EXT3-fs (sda1): mounted filesystem with ordered data mode
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: udevd[   14.325422] udevd[275]: starting version 175
. ok
[ ok ] Synthesizing the initial hotplug events...done.
[ ok ] Waiting for /dev to be fully populated...done.
[ ok ] Activating swap...done.
[....] Checking root file system...[   16.444500] random: nonblocking pool is initialized
fsck from util-linux 2.20.1
rootfs: clean, 26687/229376 files, 218516/916973 blocks
done.
[   16.687651] EXT3-fs (sda1): using internal journal
[ ok ] Loading kernel modules...done.
[ ok ] Cleaning up temporary files... /tmp /lib/init/rw.
[ ok ] Activating lvm and md swap...done.
[....] Checking file systems...fsck from util-linux 2.20.1
done.
[ ok ] Mounting local filesystems...done.
[ ok ] Activating swapfile swap...done.
[ ok ] Cleaning up temporary files....
[ ok ] Setting kernel variables ...done.
[....] Configuring network interfaces...Internet Systems Consortium DHCP Client 4.2.2
Copyright 2004-2011 Internet Systems Consortium.
All rights reserved.
For info, please visit https://www.isc.org/software/dhcp/

Listening on LPF/eth0/00:25:31:01:ca:aa
Sending on   LPF/eth0/00:25:31:01:ca:aa
Sending on   Socket/fallback
DHCPREQUEST on eth0 to 255.255.255.255 port 67
DHCPREQUEST on eth0 to 255.255.255.255 port 67
[   27.510190] stmmaceth 40400000.ethernet eth0: Link is Up - 1Gbps/Full - flow control rx/tx
DHCPDISCOVER on eth0 to 255.255.255.255 port 67 interval 5
DHCPREQUEST on eth0 to 255.255.255.255 port 67
DHCPOFFER from 192.168.7.1
DHCPACK from 192.168.7.1
bound to 192.168.7.18 -- renewal in 40004 seconds.
[   34.603210] ieee80211 phy0: rt2x00lib_request_firmware: Info - Loading firmware file 'rt2860.bin'
[   34.614799] ieee80211 phy0: rt2x00lib_request_firmware: Info - Firmware detected - version: 0.34
[   34.880737] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
Internet Systems Consortium DHCP Client 4.2.2
Copyright 2004-2011 Internet Systems Consortium.
All rights reserved.
For info, please visit https://www.isc.org/software/dhcp/

Listening on LPF/wlan0/48:5d:60:4b:88:c5
Sending on   LPF/wlan0/48:5d:60:4b:88:c5
Sending on   Socket/fallback
DHCPREQUEST on wlan0 to 255.255.255.255 port 67
[   37.774430] wlan0: authenticate with 84:1b:5e:2a:6a:d0
[   37.851258] wlan0: send auth to 84:1b:5e:2a:6a:d0 (try 1/3)
[   37.960593] wlan0: send auth to 84:1b:5e:2a:6a:d0 (try 2/3)
[   37.967641] wlan0: authenticated
[   37.980643] wlan0: associate with 84:1b:5e:2a:6a:d0 (try 1/3)
[   37.989662] wlan0: RX AssocResp from 84:1b:5e:2a:6a:d0 (capab=0x1411 status=0 aid=8)
[   37.997580] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
[   38.004193] wlan0: associated
[   38.012285] cfg80211: Calling CRDA to update world regulatory domain
[   38.054376] wlan0: Limiting TX power to 30 (30 - 0) dBm as advertised by 84:1b:5e:2a:6a:d0
DHCPDISCOVER on wlan0 to 255.255.255.255 port 67 interval 8
DHCPREQUEST on wlan0 to 255.255.255.255 port 67
DHCPOFFER from 192.168.7.1
DHCPACK from 192.168.7.1
bound to 192.168.7.28 -- renewal in 39887 seconds.
done.
[ ok ] Starting rpcbind daemon....
[ ok ] Starting NFS common utilities: statd idmapd.
[ ok ] Cleaning up temporary files....
INIT: Entering runlevel: 2
[info] Using makefile-style concurrent boot in runlevel 2.
[ ok ] Starting NTP server: ntpd.
[ ok ] Starting rpcbind daemon...[....] Already running..
[ ok ] Starting system message bus: dbus.
[....] Starting busybox' syslogd implementation : syslogdStarting /sbin/syslogd...
1902 (syslogd)
. ok
[ ok ] Starting NFS common utilities: statd idmapd.
[ ok ] Starting Avahi mDNS/DNS-SD Daemon: avahi-daemon.
[warn] Not starting NFS kernel daemon: no exports. ... (warning).
[ ok ] Starting OpenBSD Secure Shell server: sshd.
[....] Starting busybox' klogd implementation : klogdStarting /sbin/klogd...
1900 (klogd)
. ok

Debian GNU/Linux 8 PogoPro ttyS0

PogoPro login:

Edited 2 time(s). Last edit at 01/18/2017 09:47AM by daviddyer.

I normally don't run commands in uboot. For debug purpose only, I put something in bootcmd environment value in uboot

root@PogoPro:~# fw_printenv bootcmd
bootcmd=run bootcmd_uenv; run scan_disk; run set_bootargs; run bootcmd_exec; reset
root@PogoPro:~# fw_setenv bootcmd 'run start_netconsole; run bootcmd_uenv; run scan_disk; run set_bootargs; run bootcmd_exec; reset'
root@PogoPro:~# fw_printenv bootcmd
bootcmd=run start_netconsole; run bootcmd_uenv; run scan_disk; run set_bootargs; run bootcmd_exec; reset
root@PogoPro:~# reboot

I did not stop the uboot. This time, we did get something in net console.

Serial console output:

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-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
Uncompressing Linux... done, booting the kernel.
[    0.000000] Booting Linux on physical CPU 0x0
[    0.000000] Linux version 3.18.5-oxnas-tld-1 (root@tldDebian) (gcc version 4.6.3 (Debian 4.6.3-14) ) #2 SMP PREEMPT Thu Feb 12 11:17:40 PST 2015
[    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] Start Ox820 platform
[    0.000000] PERCPU: Embedded 9 pages/cpu @c7ee1000 s6624 r8192 d22048 u36864
[    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: 119396K/131072K available (5789K kernel code, 294K rwdata, 1704K rodata, 184K init, 236K bss, 11676K reserved)
[    0.000000] Virtual kernel memory layout:
[    0.000000]     vector  : 0xffff0000 - 0xffff1000   (   4 kB)
[    0.000000]     fixmap  : 0xffc00000 - 0xffe00000   (2048 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 - 0xc0759918   (7495 kB)
[    0.000000]       .init : 0xc075a000 - 0xc0788000   ( 184 kB)
[    0.000000]       .data : 0xc0788000 - 0xc07d1800   ( 294 kB)
[    0.000000]        .bss : 0xc07d1800 - 0xc080cab8   ( 237 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.000017] sched_clock: 24 bits at 390kHz, resolution 2560ns, wraps every 42949670400ns
[    0.000179] Console: colour dummy device 80x30
[    0.000209] Calibrating delay loop... 339.14 BogoMIPS (lpj=1695744)
[    0.050053] pid_max: default: 32768 minimum: 301
[    0.050222] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes)
[    0.050242] Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes)
[    0.051105] Initializing cgroup subsys net_cls
[    0.051166] CPU: Testing write buffer coherency: ok
[    0.051568] Setting up static identity map for 0x60580b78 - 0x60580bb0
[    0.110487] CPU1: Booted secondary processor
[    0.170158] Brought up 2 CPUs
[    0.170183] SMP: Total of 2 processors activated (678.29 BogoMIPS).
[    0.171092] devtmpfs: initialized
[    0.174146] prandom: seed boundary self test passed
[    0.178063] prandom: 100 self tests passed
[    0.178083] pinctrl core: initialized pinctrl subsystem
[    0.180910] NET: Registered protocol family 16
[    0.181442] DMA: preallocated 256 KiB pool for atomic coherent allocations
[    0.185745] gpio-oxnas 44000000.gpio: at address c8848000
[    0.186042] gpio-oxnas 44100000.gpio: at address c884a000
[    0.186624] pinctrl-oxnas pinctrl: initialized OX820 pinctrl driver
[    0.308856] oxnas-pcie 47c0: PCIe version/deviceID 0x82510b5
[    0.308938] oxnas-pcie 47c0: link up
[    0.309153] oxnas-pcie 47c0: PCI host bridge to bus 0000:00
[    0.309178] pci_bus 0000:00: root bus resource [mem 0x48000000-0x49ffffff]
[    0.309196] pci_bus 0000:00: root bus resource [mem 0x4a000000-0x4bdfffff pref]
[    0.309214] pci_bus 0000:00: root bus resource [io  0x0000-0xfffff]
[    0.309232] pci_bus 0000:00: root bus resource [bus 00-7f]
[    0.309657] PCI: bus0: Fast back to back transfers disabled
[    0.309734] pci 0000:00:00.0: BAR 0: assigned [mem 0x48000000-0x4800ffff]
[    0.310131] vgaarb: loaded
[    0.310638] SCSI subsystem initialized
[    0.311523] usbcore: registered new interface driver usbfs
[    0.311662] usbcore: registered new interface driver hub
[    0.311815] usbcore: registered new device driver usb
[    0.311948] pps_core: LinuxPPS API ver. 1 registered
[    0.311964] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
[    0.312012] PTP clock support registered
[    0.313443] cfg80211: Calling CRDA to update world regulatory domain
[    0.313927] Switched to clocksource rps_clocksource_timer
[    0.328046] NET: Registered protocol family 2
[    0.328995] TCP established hash table entries: 1024 (order: 0, 4096 bytes)
[    0.329036] TCP bind hash table entries: 1024 (order: 1, 8192 bytes)
[    0.329070] TCP: Hash tables configured (established 1024 bind 1024)
[    0.329152] TCP: reno registered
[    0.329177] UDP hash table entries: 256 (order: 1, 8192 bytes)
[    0.329216] UDP-Lite hash table entries: 256 (order: 1, 8192 bytes)
[    0.329507] NET: Registered protocol family 1
[    0.329973] RPC: Registered named UNIX socket transport module.
[    0.330037] RPC: Registered udp transport module.
[    0.330048] RPC: Registered tcp transport module.
[    0.330060] RPC: Registered tcp NFSv4.1 backchannel transport module.
[    0.330432] Unpacking initramfs...
[    0.561259] Freeing initrd memory: 2196K (c7c07000 - c7e2c000)
[    0.562408] futex hash table entries: 512 (order: 2, 16384 bytes)
[    0.562490] Initialise system trusted keyring
[    0.580999] NFS: Registering the id_resolver key type
[    0.581056] Key type id_resolver registered
[    0.581068] Key type id_legacy registered
[    0.581112] nfs4filelayout_init: NFSv4 File Layout Driver Registering...
[    0.581135] Installing knfsd (copyright (C) 1996 okir@monad.swb.de).
[    0.582033] fuse init (API version 7.23)
[    0.583470] msgmni has been set to 237
[    0.583976] Key type big_key registered
[    0.587768] alg: No test for stdrng (krng)
[    0.654364] Key type asymmetric registered
[    0.654394] Asymmetric key parser 'x509' registered
[    0.654535] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 252)
[    0.654566] io scheduler noop registered
[    0.654589] io scheduler deadline registered
[    0.654947] io scheduler cfq registered (default)
[    0.655488] Serial: 8250/16550 driver, 1 ports, IRQ sharing disabled
[    0.656785] console [ttyS0] disabled
[    0.656913] 44200000.uart: ttyS0 at MMIO 0x44200000 (irq = 55, base_baud = 390625) is a 16550A
[    1.225943] console [ttyS0] enabled
[    1.235125] loop: module loaded
[    1.239557] scsi host0: sata_oxnas
[    1.243591] ata1: SATA max UDMA/133 irq 50
[    1.247930] ox820sata: reseting SATA core
[    1.249108] nand: Could not find valid ONFI parameter page; aborting
[    1.249152] nand: device found, Manufacturer ID: 0xad, Chip ID: 0xf1
[    1.249154] nand: Hynix NAND 128MiB 3,3V 8-bit
[    1.249164] nand: 128MiB, SLC, page size: 2048, OOB size: 64
[    1.249180] Scanning device for bad blocks
[    1.310604] Bad eraseblock 769 at 0x000006020000
[    1.335462] 2 cmdlinepart partitions found on MTD device 41000000.nand
[    1.341977] Creating 2 MTD partitions on "41000000.nand":
[    1.347353] 0x000000000000-0x000000e00000 : "boot"
[    1.353625] 0x000000e00000-0x000008000000 : "data"
[    1.360384] stmmaceth 40400000.ethernet: no reset control found
[    1.366330] stmmac - user ID: 0x12, Synopsys ID: 0x35
[    1.371371]  Ring mode enabled
[    1.374405]  DMA HW capability register supported
[    1.378905]  Enhanced/Alternate descriptors
[    1.383247]  Enabled extended descriptors
[    1.387230]  RX Checksum Offload Engine supported (type 2)
[    1.392696]  TX Checksum insertion supported
[    1.396938]  Wake-Up On Lan supported
[    1.400588]  Enable RX Mitigation via HW Watchdog Timer
[    1.427015] libphy: stmmac: probed
[    1.430407] eth0: PHY ID 001cc914 at 0 IRQ POLL (stmmac-0:00) active
[    1.436797] eth0: PHY ID 001cc914 at 3 IRQ POLL (stmmac-0:03)
[    1.442693] PPP generic driver version 2.4.2
[    1.447303] rt2800pci 0000:00:00.0: enabling device (0140 -> 0142)
[    1.453672] ieee80211 phy0: rt2x00_set_rt: Info - RT chipset 3090, rev 3213 detected
[    1.464842] ieee80211 phy0: rt2x00_set_rf: Info - RF chipset 0005 detected
[    1.473689] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[    1.480199] ehci-pci: EHCI PCI platform driver
[    1.485025] oxnas-ehci 40200100.ehci: EHCI Host Controller
[    1.490521] oxnas-ehci 40200100.ehci: new USB bus registered, assigned bus number 1
[    1.498314] oxnas-ehci 40200100.ehci: irq 39, io mem 0x40200100
[    1.520593] oxnas-ehci 40200100.ehci: USB 2.0 started, EHCI 1.00
[    1.526860] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002
[    1.533649] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    1.540856] usb usb1: Product: EHCI Host Controller
[    1.545710] usb usb1: Manufacturer: Linux 3.18.5-oxnas-tld-1 ehci_hcd
[    1.552133] usb usb1: SerialNumber: 40200100.ehci
[    1.557665] hub 1-0:1.0: USB hub found
[    1.561510] hub 1-0:1.0: 2 ports detected
[    1.566118] usbcore: registered new interface driver cdc_acm
[    1.571786] cdc_acm: USB Abstract Control Model driver for USB modems and ISDN adapters
[    1.579850] usbcore: registered new interface driver cdc_wdm
[    1.585666] usbcore: registered new interface driver usb-storage
[    1.591892] usbcore: registered new interface driver usbserial
[    1.597760] usbcore: registered new interface driver usbserial_generic
[    1.604344] usbserial: USB Serial support registered for generic
[    1.610705] mousedev: PS/2 mouse device common for all mice
[    1.616268] i2c /dev entries driver
[    1.620851] usbcore: registered new interface driver usbhid
[    1.626398] usbhid: USB HID core driver
[    1.630036] ata1: SATA link down (SStatus 0 SControl 310)
[    1.635919] TCP: cubic registered
[    1.640363] NET: Registered protocol family 10
[    1.646597] sit: IPv6 over IPv4 tunneling driver
[    1.652293] NET: Registered protocol family 17
[    1.656875] Key type dns_resolver registered
[    1.662277] Loading compiled-in X.509 certificates
[    1.670929] Freeing unused kernel memory: 184K (c075a000 - c0788000)
Loading, please wait...
[    1.807936] udevd[67]: starting version 175
[    1.880826] usb 1-1: new high-speed USB device number 2 using oxnas-ehci
[    2.043087] usb 1-1: New USB device found, idVendor=05e3, idProduct=0608
[    2.049766] usb 1-1: New USB device strings: Mfr=0, Product=1, SerialNumber=0
[    2.075343] usb 1-1: Product: USB2.0 Hub
[    2.094891] hub 1-1:1.0: USB hub found
[    2.151219] hub 1-1:1.0: 4 ports detected
[    2.451013] usb 1-1.1: new high-speed USB device number 3 using oxnas-ehci
[    2.561625] usb 1-1.1: New USB device found, idVendor=0781, idProduct=5573
[    2.568476] usb 1-1.1: New USB device strings: Mfr=1, Product=2, SerialNumber=3
[    2.575777] usb 1-1.1: Product: Staples
[    2.579599] usb 1-1.1: Manufacturer:
[    2.585935] usb 1-1.1: SerialNumber: 4C532000011009101052
[    2.593361] usb-storage 1-1.1:1.0: USB Mass Storage device detected
[    2.600230] scsi host1: usb-storage 1-1.1:1.0
[    2.620759] usbcore: registered new interface driver uas
[    3.601971] scsi 1:0:0:0: Direct-Access     Staples                   1.22 PQ: 0 ANSI: 5
[    3.612126] sd 1:0:0:0: [sda] 7821312 512-byte logical blocks: (4.00 GB/3.72 GiB)
[    3.621230] sd 1:0:0:0: [sda] Write Protect is off
[    3.626967] sd 1:0:0:0: [sda] Write cache: disabled, read cache: enabled, doesn't support DPO or FUA
[    3.644554]  sda: sda1 sda2
[    3.651975] sd 1:0:0:0: [sda] Attached SCSI removable disk
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: Checking root file system ... fsck from util-linux 2.20.1
rootfs: clean, 26687/229376 files, 218517/916973 blocks
done.
[   12.661844] kjournald starting.  Commit interval 5 seconds
[   12.661921] EXT3-fs (sda1): mounted filesystem with ordered data mode
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: udevd[   14.315512] udevd[277]: starting version 175
. ok
[ ok ] Synthesizing the initial hotplug events...done.
[ ok ] Waiting for /dev to be fully populated...done.
[....] Activating swap...[   16.253568] random: nonblocking pool is initialized
done.
[....] Checking root file system...fsck from util-linux 2.20.1
rootfs: clean, 26687/229376 files, 218517/916973 blocks
done.
[   16.657589] EXT3-fs (sda1): using internal journal
[ ok ] Loading kernel modules...done.
[ ok ] Cleaning up temporary files... /tmp /lib/init/rw.
[ ok ] Activating lvm and md swap...done.
[....] Checking file systems...fsck from util-linux 2.20.1
done.
[ ok ] Mounting local filesystems...done.
[ ok ] Activating swapfile swap...done.
[ ok ] Cleaning up temporary files....
[ ok ] Setting kernel variables ...done.
[....] Configuring network interfaces...Internet Systems Consortium DHCP Client 4.2.2
Copyright 2004-2011 Internet Systems Consortium.
All rights reserved.
For info, please visit https://www.isc.org/software/dhcp/

Listening on LPF/eth0/00:25:31:01:ca:aa
Sending on   LPF/eth0/00:25:31:01:ca:aa
Sending on   Socket/fallback
DHCPREQUEST on eth0 to 255.255.255.255 port 67
[   27.310748] stmmaceth 40400000.ethernet eth0: Link is Up - 1Gbps/Full - flow control rx/tx
DHCPREQUEST on eth0 to 255.255.255.255 port 67
DHCPACK from 192.168.7.1
bound to 192.168.7.18 -- renewal in 41994 seconds.
[   30.476459] ieee80211 phy0: rt2x00lib_request_firmware: Info - Loading firmware file 'rt2860.bin'
[   30.488005] ieee80211 phy0: rt2x00lib_request_firmware: Info - Firmware detected - version: 0.34
[   32.691307] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
Internet Systems Consortium DHCP Client 4.2.2
Copyright 2004-2011 Internet Systems Consortium.
All rights reserved.
For info, please visit https://www.isc.org/software/dhcp/

Listening on LPF/wlan0/48:5d:60:4b:88:c5
Sending on   LPF/wlan0/48:5d:60:4b:88:c5
Sending on   Socket/fallback
DHCPREQUEST on wlan0 to 255.255.255.255 port 67
[   35.583255] wlan0: authenticate with 84:1b:5e:2a:6a:d0
[   35.660997] wlan0: send auth to 84:1b:5e:2a:6a:d0 (try 1/3)
[   35.670169] wlan0: authenticated
[   35.680655] wlan0: associate with 84:1b:5e:2a:6a:d0 (try 1/3)
[   35.689676] wlan0: RX AssocResp from 84:1b:5e:2a:6a:d0 (capab=0x1411 status=0 aid=8)
[   35.697797] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
[   35.704222] wlan0: associated
[   35.712401] cfg80211: Calling CRDA to update world regulatory domain
[   35.771217] wlan0: Limiting TX power to 30 (30 - 0) dBm as advertised by 84:1b:5e:2a:6a:d0
DHCPDISCOVER on wlan0 to 255.255.255.255 port 67 interval 8
DHCPREQUEST on wlan0 to 255.255.255.255 port 67
DHCPOFFER from 192.168.7.1
DHCPACK from 192.168.7.1
bound to 192.168.7.28 -- renewal in 36266 seconds.
done.
[ ok ] Starting rpcbind daemon....
[ ok ] Starting NFS common utilities: statd idmapd.
[ ok ] Cleaning up temporary files....
INIT: Entering runlevel: 2
[info] Using makefile-style concurrent boot in runlevel 2.
[ ok ] Starting rpcbind daemon...[....] Already running..
[ ok ] Starting NTP server: ntpd.
[....] Starting busybox' syslogd implementation : syslogdStarting /sbin/syslogd...
1888 (syslogd)
. ok
[ ok ] Starting system message bus: dbus.
[ ok ] Starting NFS common utilities: statd idmapd.
[ ok ] Starting Avahi mDNS/DNS-SD Daemon: avahi-daemon.
[warn] Not starting NFS kernel daemon: no exports. ... (warning).
[ ok ] Starting OpenBSD Secure Shell server: sshd.
[....] Starting busybox' klogd implementation : klogdStarting /sbin/klogd...
1885 (klogd)
. ok

Debian GNU/Linux 8 PogoPro ttyS0

PogoPro login:

Netconsole output:

root@BackUpServer:~# netcat -lup 6666 192.168.7.18 6666

U-Boot 2015.10-tld-1 (Nov 21 2015 - 01:17:59 -0800)
OXNAS OX820
gcc (Debian 4.9.2-10) 4.9.2
GNU ld (GNU Binutils for Debian) 2.25
starting USB...
USB0:   USB EHCI 1.00
scanning bus 0 for devices... 3 USB Device(s) found
       scanning usb for storage devices... 1 Storage Device(s) found

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     51              7335789         00000000-01     83 Boot
  2     7335840         485112          00000000-02     82
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
1 bytes read in 210 ms (0 Bytes/s)
Found bootable drive on usb 0
loading uImage ...
4170064 bytes read in 457 ms (8.7 MiB/s)
loading uInitrd ...
2245099 bytes read in 404 ms (5.3 MiB/s)
loading DTB /boot/dts/ox820-pogoplug-pro.dtb ...
7029 bytes read in 237 ms (28.3 KiB/s)
## Booting kernel from Legacy Image at 60500000 ...
   Image Name:   Linux-3.18.5-oxnas-tld-1
   Created:      2015-10-23   2:26:57 UTC
   Image Type:   ARM Linux Kernel Image (uncompressed)
   Data Size:    4170000 Bytes = 4 MiB
   Load Address: 60008000
   Entry Point:  60008000
   Verifying Checksum ... OK
## Loading init Ramdisk from Legacy Image at 60e00000 ...
   Image Name:   initramfs-3.18.5-oxnas-tld-1
   Created:      2015-10-23   2:29:24 UTC
   Image Type:   ARM Linux RAMDisk Image (gzip compressed)
   Data Size:    2245035 Bytes = 2.1 MiB
   Load Address: 60000000
   Entry Point:  60000000
   Verifying Checksum ... OK
## Flattened Device Tree blob at 62c00000
   Booting using the fdt blob at 0x62c00000


Starting kernel ...

Well, I can't stop the netconsole since there was no choice...

Otherwise, it just worked as normal.

So I guess the problem is simple:

1 uboot network is alive.
2 for some reason, the netconsole does NOT start at the beginning.
3 net console can work if we start it manually. :P



Edited 1 time(s). Last edit at 01/18/2017 09:27AM by daviddyer.

If you need any more tests, please tell me.

Thank you for your time.

All my Kirkwood boxes and OX820 boxes say thank you!

> 1 uboot network is alive.
> 2 for some reason, the netconsole does NOT start
> at the beginning.
> 3 net console can work if we start it manually. :P

OK David, thanks for the tests! I'll take a look with my Pogo Pro to see if I can repeat it.

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

Hi daviddyer,

Thanks for the report. I've run the test, and indeed, I saw the same behavior! netconsole did not start automatically.

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

Hope we can get the fix soon.

Again, all my Kirkwood boxes, OX820 boxes say thank you!

Hi David,

It will be a while until I'll release new u-boot for this. Just a simple config switch that I've somehow missed during rebasing u-boot to 2015 version! but it will need rebuild to activate preboot again. If you can't live without it, pls let me know to raise its priority on my list.

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



Edited 1 time(s). Last edit at 01/24/2017 06:48AM by bodhi.

I'm OK since I have serial cables for these boxes. For those who don't have serial cable, net console is their only reasonable clue for the box.

BTW, for anyone who is interested in OX820 boxes, eBay now has pogo pro for $10 from a reputable merchant. Pogo Pro has 4 usb 2.0, one sata , giga NIC, wireless N.