ZyXEL NSA320: eth0 "link is not ready"

[CharlesB]

I'm splitting this issue from the original thread (http://archlinuxarm.org/forum/viewtopic.php?f=27&t=3708).

My Ethernet port doesn't want to come up.
Here's the complete boot-up:
$this->bbcode_second_pass_code('', ' __ __ _ _
| \/ | __ _ _ ____ _____| | |
| |\/| |/ _` | '__\ \ / / _ \ | |
| | | | (_| | | \ V / __/ | |
|_| |_|\__,_|_| \_/ \___|_|_|
_ _ ____ _
| | | | | __ ) ___ ___ | |_
| | | |___| _ \ / _ \ / _ \| __|
| |_| |___| |_) | (_) | (_) | |_
\___/ |____/ \___/ \___/ \__|
** MARVELL BOARD: RD-88F6281A LE

U-Boot 1.1.4 (Mar 23 2011 - 16:09:39) Marvell version: 3.4.19

U-Boot code: 00600000 -> 0067FFF0 BSS: -> 006CFEE0

Soc: 88F6281 A1 (DDR2)
CPU running @ 1200Mhz L2 running @ 400Mhz
SysClock = 400Mhz , TClock = 200Mhz

DRAM CAS Latency = 5 tRP = 5 tRAS = 18 tRCD=6
DRAM CS[0] base 0x00000000 size 256MB
DRAM CS[1] base 0x10000000 size 256MB
DRAM Total size 512MB 16bit width
Addresses 10M - 0M are saved for the U-Boot usage.
Mem malloc Initialization (10M - 7M): Done
NAND:128 MB
Flash: 0 kB

CPU : Marvell Feroceon (Rev 1)
//--- stateButtonBit = 3, recovery ---//
Kernel address is 0xc80000.

Streaming disabled
Write allocate disabled

Module 0 is RGMII
Module 1 is TDM

USB 0: host mode
PEX 0: interface detected no Link.
Net: egiga0, egiga1 [PRIME]
Hit any key to stop autoboot: 2 1 0

Reset IDE:
Marvell Serial ATA Adapter
Integrated Sata device found
[0 0 0]: Enable DMA mode (5)
Device 0 @ 0 0:
Model: WDC WD3200JD-22KLB0 Firm: 08.05J08 Ser#: WD-WCAMR2238476
Type: Hard Disk
Supports 48-bit addressing
Capacity: 305245.3 MB = 298.0 GB (625142448 x 512)


2856016 bytes read
## Booting image at 02000000 ...
Image Name: Linux-3.1.10-13-ARCH
Created: 2012-08-20 15:46:58 UTC
Image Type: ARM Linux Kernel Image (uncompressed)
Data Size: 2855952 Bytes = 2.7 MB
Load Address: 00008000
Entry Point: 00008000
Verifying Checksum ... OK
OK

Starting kernel ...

Uncompressing Linux... done, booting the kernel.
[ 0.000000] Initializing cgroup subsys cpuset
[ 0.000000] Initializing cgroup subsys cpu
[ 0.000000] Linux version 3.1.10-13-ARCH (nobody@fermium) (gcc version 4.7.1 20120721 (prerelease) (GCC) ) #1 PREEMPT Mon Aug 20 15:46:18 UTC 2012
[ 0.000000] CPU: Feroceon 88FR131 [56251311] revision 1 (ARMv5TE), cr=00053977
[ 0.000000] CPU: VIVT data cache, VIVT instruction cache
[ 0.000000] Machine: ZyXEL NSA320 2-Bay Power Media Server
[ 0.000000] Memory policy: ECC disabled, Data cache writeback
[ 0.000000] On node 0 totalpages: 131072
[ 0.000000] free_area_init_node: node 0, pgdat c058c780, node_mem_map c065d000
[ 0.000000] Normal zone: 1024 pages used for memmap
[ 0.000000] Normal zone: 0 pages reserved
[ 0.000000] Normal zone: 130048 pages, LIFO batch:31
[ 0.000000] pcpu-alloc: s0 r0 d32768 u32768 alloc=1*32768
[ 0.000000] pcpu-alloc: [0] 0
[ 0.000000] Built 1 zonelists in Zone order, mobility grouping on. Total pages: 130048
[ 0.000000] Kernel command line: console=ttyS0,115200 mtdparts=nand_mtd:0x100000(uboot),0x80000(uboot_env),0x80000(key_store),0x80000(info),0xA00000(etc),0xA00000(kernel_1),0x2FC0000(rootfs1),0xA00000(kernel_2),0x2FC0000(rootfs2) root=/dev/sda2 ro rootwait loglevel=8
[ 0.000000] PID hash table entries: 2048 (order: 1, 8192 bytes)
[ 0.000000] Dentry cache hash table entries: 65536 (order: 6, 262144 bytes)
[ 0.000000] Inode-cache hash table entries: 32768 (order: 5, 131072 bytes)
[ 0.000000] allocated 2097152 bytes of page_cgroup
[ 0.000000] please try 'cgroup_disable=memory' option if you don't want memory cgroups
[ 0.000000] Memory: 512MB = 512MB total
[ 0.000000] Memory: 511192k/511192k available, 13096k reserved, 0K highmem
[ 0.000000] Virtual kernel memory layout:
[ 0.000000] vector : 0xffff0000 - 0xffff1000 ( 4 kB)
[ 0.000000] fixmap : 0xfff00000 - 0xfffe0000 ( 896 kB)
[ 0.000000] DMA : 0xffc00000 - 0xffe00000 ( 2 MB)
[ 0.000000] vmalloc : 0xe0800000 - 0xfe800000 ( 480 MB)
[ 0.000000] lowmem : 0xc0000000 - 0xe0000000 ( 512 MB)
[ 0.000000] modules : 0xbf000000 - 0xc0000000 ( 16 MB)
[ 0.000000] .text : 0xc0008000 - 0xc0529b6c (5255 kB)
[ 0.000000] .init : 0xc052a000 - 0xc0555000 ( 172 kB)
[ 0.000000] .data : 0xc0556000 - 0xc058ef70 ( 228 kB)
[ 0.000000] .bss : 0xc058ef94 - 0xc065cb0c ( 823 kB)
[ 0.000000] SLUB: Genslabs=13, HWalign=32, Order=0-3, MinObjects=0, CPUs=1, Nodes=1
[ 0.000000] Preemptible hierarchical RCU implementation.
[ 0.000000] NR_IRQS:114
[ 0.000000] sched_clock: 32 bits at 200MHz, resolution 5ns, wraps every 21474ms
[ 0.000000] Console: colour dummy device 80x30
[ 13.880585] Calibrating delay loop... 1196.85 BogoMIPS (lpj=5984256)
[ 13.970523] pid_max: default: 32768 minimum: 301
[ 13.970638] Security Framework initialized
[ 13.970659] AppArmor: AppArmor disabled by boot time parameter
[ 13.970725] Mount-cache hash table entries: 512
[ 13.971084] Initializing cgroup subsys cpuacct
[ 13.971108] Initializing cgroup subsys memory
[ 13.971141] Initializing cgroup subsys devices
[ 13.971153] Initializing cgroup subsys freezer
[ 13.971162] Initializing cgroup subsys net_cls
[ 13.971171] Initializing cgroup subsys blkio
[ 13.971192] Initializing cgroup subsys perf_event
[ 13.971280] CPU: Testing write buffer coherency: ok
[ 13.972426] devtmpfs: initialized
[ 13.974175] NET: Registered protocol family 16
[ 13.974760] Kirkwood: MV88F6281-A1, TCLK=200000000.
[ 13.974773] Feroceon L2: Enabling L2
[ 13.974807] Feroceon L2: Cache support initialised.
[ 13.975319] initial MPP regs: 21111111 01003311 33331100 33003333 00000533 20202000 00000000
[ 13.975355] final MPP regs: 01111111 00003311 00001100 00000000 00000000 00000000 00000000
[ 13.980465] bio: create slab <bio-0> at 0
[ 13.980845] vgaarb: loaded
[ 13.981107] SCSI subsystem initialized
[ 13.981258] libata version 3.00 loaded.
[ 13.981470] usbcore: registered new interface driver usbfs
[ 13.981557] usbcore: registered new interface driver hub
[ 13.981658] usbcore: registered new device driver usb
[ 13.982195] Switching to clocksource orion_clocksource
[ 13.990519] Switched to NOHz mode on CPU #0
[ 13.990720] FS-Cache: Loaded
[ 14.000414] NET: Registered protocol family 2
[ 14.000635] IP route cache hash table entries: 4096 (order: 2, 16384 bytes)
[ 14.001335] TCP established hash table entries: 16384 (order: 5, 131072 bytes)
[ 14.001688] TCP bind hash table entries: 16384 (order: 4, 65536 bytes)
[ 14.001866] TCP: Hash tables configured (established 16384 bind 16384)
[ 14.001875] TCP reno registered
[ 14.001886] UDP hash table entries: 256 (order: 0, 4096 bytes)
[ 14.001908] UDP-Lite hash table entries: 256 (order: 0, 4096 bytes)
[ 14.002135] NET: Registered protocol family 1
[ 14.002169] PCI: CLS 0 bytes, default 32
[ 14.002775] audit: initializing netlink socket (disabled)
[ 14.002808] type=2000 audit(0.120:1): initialized
[ 14.088377] VFS: Disk quotas dquot_6.5.2
[ 14.088639] Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
[ 14.090813] JFFS2 version 2.2. (NAND) © 2001-2006 Red Hat, Inc.
[ 14.092109] aufs 3.1-20111107
[ 14.092130] msgmni has been set to 998
[ 14.093473] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 253)
[ 14.093488] io scheduler noop registered
[ 14.093496] io scheduler deadline registered
[ 14.093594] io scheduler cfq registered (default)
[ 14.093655] mv_xor_shared mv_xor_shared.0: Marvell shared XOR driver
[ 14.093685] mv_xor_shared mv_xor_shared.1: Marvell shared XOR driver
[ 14.132271] mv_xor mv_xor.0: Marvell XOR: ( xor cpy )
[ 14.172278] mv_xor mv_xor.1: Marvell XOR: ( xor fill cpy )
[ 14.212266] mv_xor mv_xor.2: Marvell XOR: ( xor cpy )
[ 14.252265] mv_xor mv_xor.3: Marvell XOR: ( xor fill cpy )
[ 14.257273] Serial: 8250/16550 driver, 2 ports, IRQ sharing disabled
[ 14.277929] serial8250.0: ttyS0 at MMIO 0xf1012000 (irq = 33) is a 16550A
[ 14.819743] console [ttyS0] enabled
[ 14.829233] brd: module loaded
[ 14.835263] loop: module loaded
[ 14.838698] sata_mv sata_mv.0: version 1.28
[ 14.843044] sata_mv sata_mv.0: slots 32 ports 2
[ 14.849234] scsi0 : sata_mv
[ 14.852460] scsi1 : sata_mv
[ 14.855474] ata1: SATA max UDMA/133 irq 21
[ 14.859590] ata2: SATA max UDMA/133 irq 21
[ 14.864602] NAND device: Manufacturer ID: 0xec, Chip ID: 0xf1 (Samsung NAND 128MiB 3,3V 8-bit)
[ 14.873464] Scanning device for bad blocks
[ 14.907604] Bad eraseblock 286 at 0x0000023c0000
[ 14.921613] Bad eraseblock 376 at 0x000002f00000
[ 14.944539] Bad eraseblock 551 at 0x0000044e0000
[ 14.969122] Bad eraseblock 742 at 0x000005cc0000
[ 15.001348] Bad eraseblock 1006 at 0x000007dc0000
[ 15.007887] Creating 9 MTD partitions on "orion_nand":
[ 15.013073] 0x000000000000-0x000000100000 : "uboot"
[ 15.018913] 0x000000100000-0x000000180000 : "uboot_env"
[ 15.024974] 0x000000180000-0x000000200000 : "key_store"
[ 15.030975] 0x000000200000-0x000000280000 : "info"
[ 15.036596] 0x000000280000-0x000000c80000 : "etc"
[ 15.042123] 0x000000c80000-0x000001680000 : "kernel_1"
[ 15.048133] 0x000001680000-0x000004640000 : "rootfs1"
[ 15.054112] 0x000004640000-0x000005040000 : "kernel_2"
[ 15.060120] 0x000005040000-0x000008000000 : "rootfs2"
[ 15.066898] mv643xx_eth: MV-643xx 10/100/1000 ethernet driver version 1.4
[ 15.073847] mv643xx_eth smi: probed
[ 15.079416] mv643xx_eth_port mv643xx_eth_port.0: eth0: port 0 with MAC address b0:b2:dc:ed:59:3d
[ 15.088378] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[ 15.095009] orion-ehci orion-ehci.0: Marvell Orion EHCI
[ 15.100314] orion-ehci orion-ehci.0: new USB bus registered, assigned bus number 1
[ 15.132246] orion-ehci orion-ehci.0: irq 19, io mem 0xf1050000
[ 15.152232] orion-ehci orion-ehci.0: USB 2.0 started, EHCI 1.00
[ 15.158704] hub 1-0:1.0: USB hub found
[ 15.162520] hub 1-0:1.0: 1 port detected
[ 15.166928] Initializing USB Mass Storage driver...
[ 15.171903] usbcore: registered new interface driver usb-storage
[ 15.177962] USB Mass Storage support registered.
[ 15.182732] usbcore: registered new interface driver libusual
[ 15.188818] mousedev: PS/2 mouse device common for all mice
[ 15.194669] rtc-mv rtc-mv: rtc core: registered rtc-mv as rtc0
[ 15.200658] sdhci: Secure Digital Host Controller Interface driver
[ 15.206907] sdhci: Copyright(c) Pierre Ossman
[ 15.211347] sdhci-pltfm: SDHCI platform and OF driver helper
[ 15.217181] Registered led device: nsa320:green:hdd2
[ 15.222336] Registered led device: nsa320:red:hdd2
[ 15.227229] Registered led device: nsa320:green:usb
[ 15.232207] Registered led device: nsa320:green:sys
[ 15.237206] Registered led device: nsa320:orange:sys
[ 15.242310] Registered led device: nsa320:green:copy
[ 15.247381] Registered led device: nsa320:red:copy
[ 15.252297] Registered led device: nsa320:green:hdd1
[ 15.257370] Registered led device: nsa320:red:hdd1
[ 15.262480] usbcore: registered new interface driver r8712u
[ 15.268951] GRE over IPv4 demultiplexor driver
[ 15.273442] TCP cubic registered
[ 15.276868] NET: Registered protocol family 17
[ 15.281411] lib80211: common routines for IEEE802.11 drivers
[ 15.287119] lib80211_crypt: registered algorithm 'NULL'
[ 15.292386] Registering the dns_resolver key type
[ 15.297145] Gating clock of unused units
[ 15.301081] before: 0x00dfc3dd
[ 15.304161] after: 0x00c7c1c9
[ 15.307732] registered taskstats version 1
[ 15.312490] console [netcon0] enabled
[ 15.316166] netconsole: network logging started
[ 15.321283] input: gpio-keys as /devices/platform/gpio-keys/input/input0
[ 15.328293] rtc-mv rtc-mv: setting system clock to 2084-11-07 06:45:55 UTC (3624417955)
[ 15.382255] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl F300)
[ 15.422279] ata1.00: ATA-6: WDC WD3200JD-22KLB0, 08.05J08, max UDMA/100
[ 15.428940] ata1.00: 625142448 sectors, multi 0: LBA48
[ 15.452455] ata1.00: configured for UDMA/100
[ 15.472531] scsi 0:0:0:0: Direct-Access ATA WDC WD3200JD-22K 08.0 PQ: 0 ANSI: 5
[ 15.481178] sd 0:0:0:0: [sda] 625142448 512-byte logical blocks: (320 GB/298 GiB)
[ 15.489018] sd 0:0:0:0: Attached scsi generic sg0 type 0
[ 15.494399] usb 1-1: new high speed USB device number 2 using orion-ehci
[ 15.501400] sd 0:0:0:0: [sda] Write Protect is off
[ 15.506614] sd 0:0:0:0: [sda] Mode Sense: 00 3a 00 00
[ 15.511796] sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
[ 15.529624] sda: sda1 sda2
[ 15.533576] sd 0:0:0:0: [sda] Attached SCSI disk
[ 15.654321] hub 1-1:1.0: USB hub found
[ 15.658447] hub 1-1:1.0: 4 ports detected
[ 15.852247] ata2: SATA link down (SStatus 0 SControl F300)
[ 15.878620] EXT4-fs (sda2): couldn't mount as ext3 due to feature incompatibilities
[ 15.902471] EXT4-fs (sda2): couldn't mount as ext2 due to feature incompatibilities
[ 16.096475] EXT4-fs (sda2): mounted filesystem with ordered data mode. Opts: (null)
[ 16.104219] VFS: Mounted root (ext4 filesystem) readonly on device 8:2.
[ 16.141180] devtmpfs: mounted
[ 16.144687] Freeing init memory: 172K

INIT: version 2.88 booting


> Arch Linux ARM

> http://archlinuxarm.org/

------------------------------
:: Adjusting system time and setting kernel time zone [BUSY] hwclock: settimeofday() failed: Invalid argument

Unable to set system clock.

[FAIL]

:: Starting udev daemon [BUSY] systemd-udevd[93]: starting version 188
[1m[DONE]

:: Triggering udev uevents [BUSY] [DONE]

:: Loading user-specified modules [BUSY] [DONE]

:: Waiting for udev uevents to be processed [BUSY] [DONE]

:: Configuring virtual consoles [BUSY] [DONE]

:: Bringing up loopback interface [BUSY] [DONE]

:: Checking filesystems [BUSY] [DONE]

:: Remounting root and API filesystems [BUSY] [ 18.503364] EXT4-fs (sda2): warning: checktime reached, running e2fsck is recommended
[ 18.511709] EXT4-fs (sda2): re-mounted. Opts: user_xattr,acl,barrier=1,data=ordered
[DONE]

:: Mounting local filesystems [BUSY] [DONE]

:: Activating swap [BUSY] [DONE]

:: Configuring time zone [BUSY] [DONE]

:: Initializing random seed [BUSY] [DONE]

:: Removing leftover files [BUSY] [DONE]

:: Setting hostname: alarm [BUSY] [DONE]

:: Saving dmesg log [BUSY] [DONE]


INIT: Entering runlevel: 3


:: Starting Syslog-NG [BUSY] [DONE]

:: Starting network [BUSY] [DONE]

:: Mounting network filesystems [BUSY] [DONE]

:: Starting crond daemon [BUSY] [DONE]

:: Starting Secure Shell Daemon [BUSY] [ 51.316931] NET: Registered protocol family 10
[ 51.340794] ADDRCONF(NETDEV_UP): eth0: link is not ready
[DONE]
')
You can see that the driver identifies the device, displaying its MAC address. It pauses a long time on "Starting network", suggesting that it's trying to get a dhcp lease, and then finally, at the end, you see the "link is not ready" message. The same message appears if you manually take it down and back up:
$this->bbcode_second_pass_code('', '[root@alarm ~]# ifconfig
eth0: flags=4163<UP,BROADCAST,RUNNING,MULTICAST> mtu 1500
ether b0:b2:dc:ed:59:3d txqueuelen 1000 (Ethernet)
RX packets 0 bytes 0 (0.0 B)
RX errors 0 dropped 0 overruns 0 frame 0
TX packets 0 bytes 0 (0.0 B)
TX errors 0 dropped 0 overruns 0 carrier 0 collisions 0
device interrupt 11

lo: flags=73<UP,LOOPBACK,RUNNING> mtu 16436
inet 127.0.0.1 netmask 255.0.0.0
inet6 ::1 prefixlen 128 scopeid 0x10<host>
loop txqueuelen 0 (Local Loopback)
RX packets 4 bytes 260 (260.0 B)
RX errors 0 dropped 0 overruns 0 frame 0
TX packets 4 bytes 260 (260.0 B)
TX errors 0 dropped 0 overruns 0 carrier 0 collisions 0

[root@alarm ~]# ifconfig ehth0 down
[root@alarm ~]# ifconfig eth0 downup
[ 114.621007] ADDRCONF(NETDEV_UP): eth0: link is not ready
[root@alarm ~]# ')

What I know is NOT the problem:
1. Bad cable/switch -- The same cable works fine for any other machine.
2. DHCP -- When I set up a PogoPlug for someone else, I found that the dhcp client was, for some reason, unable to get a lease from his particular DHCP server (worked fine on mine!), and I first suspected that. However, WireShark shows absolutely no Ethernet traffic coming from this machine. (Before I attached a serial terminal, I didn't know the MAC address, so I filtered out traffic from all known devices, and there is absolutely nothing else that shows up, so I didn't just mistype the MAC address or anything like that -- there really is nothing.)

The "link is not ready" suggest to my uninformed mind that it's a link-layer problem, so I wouldn't expect to see any traffic.
To me, that leaves the possibility of:
1. Physical problem between the Ethernet jack and the chip driving it (the pins in the jack look good) or
2. For some reason the driver's not detecting the link status

The switch I have it connected to shows a 1GB link as expected. The NSA320's port has two LED's on it. The top one is on all the time (as long as the power supply is plugged in), even with it off, and blinks with traffic from the switch. The bottom one has never come on. I don't know what its official purpose is.

Ideas?

[CharlesB]

$this->bbcode_second_pass_quote('WarheadsSE', 'W')hat kind of switch is it attached to? We've seen issues with some switches/routes with switches.

[WarheadsSE]

We have seen auto-negotiation failures on some devices with some switches. What is the speed of the switch?

[CharlesB]

Tried it with two other switches, a generic Gigabit switch (I don't remember the brand, but I use 5 of them) and a Netgear FS105, all with the same result.

[WarheadsSE]

And I assume you had no issues with the original firmware?

[CharlesB]

$this->bbcode_second_pass_quote('WarheadsSE', 'A')nd I assume you had no issues with the original firmware?

[CharlesB]

$this->bbcode_second_pass_quote('', ' ')It's an "AirLink +"

[WarheadsSE]

Oh, I know how the auto-neg protocol works. Doesn't mean it always works. Obviously, something funky is happening.

[CharlesB]

Just for grins, I connected a USB Ethernet adapter, and after manually assigning an address,etc. (It came up IPv6-only, and I didn't know how to tell it to try to get an address via DHCP) I was able to use it to download/install the parted package. Not that I would want to run a fileserver on USB, but it provided additional confirmation that the problem is limited to the specific adapter.

I went ahead and forced an IP address on eth0 and tried using it, but of course it didn't work -- didn't even make the light blink when I sent a flood ping.

[WarheadsSE]

Definitely an oddity.

If you are up for it, consider trying the linux-kirkwood kernel package, moves from 3.1.10 to 3.5.4, might have a mild driver tweak