#18449: reboot / crash b44 : NETDEV WATCHDOG: eth0 (b44): transmit queue 0 timed
out
-------------------------------------+-------------------------------------
Reporter: anonymous | Owner: developers
Type: defect | Status: new
Priority: high | Milestone:
Component: kernel | Version: Attitude Adjustment
Keywords: | 12.09
b44,brcm47xx,wl,crash,eth0 |
-------------------------------------+-------------------------------------
Installed AA 12.09 on Asus WL-500W.
Installed wl driver (removed b43 driver).
Initially everything looked OK, however after some time the router started
to spontaneously reboot. Looking the dmesg and logread output I noticed
the following:
--
[ 0.000000] Linux version 3.3.8 (blogic@Debian-60-squeeze-64-minimal)
(gcc version 4.6.3 20120201 (prerelease) (Linaro GCC 4.6-2012.02) ) #1 Sat
Mar 23 16:01:31 UTC 2013
[ 0.000000] CPU revision is: 00029006 (Broadcom BMIPS3300)
[ 0.000000] bcm47xx: using ssb bus
[ 0.000000] ssb: Found chip with id 0x4704, rev 0x09 and package 0x00
[ 0.000000] ssb: Core 0 found: ChipCommon (cc 0x800, rev 0x03, vendor
0x4243)
[ 0.000000] ssb: Core 1 found: Fast Ethernet (cc 0x806, rev 0x06,
vendor 0x4243)
[ 0.000000] ssb: Core 2 found: Fast Ethernet (cc 0x806, rev 0x06,
vendor 0x4243)
[ 0.000000] ssb: Core 3 found: USB 1.1 Hostdev (cc 0x808, rev 0x03,
vendor 0x4243)
[ 0.000000] ssb: Core 4 found: PCI (cc 0x804, rev 0x08, vendor 0x4243)
[ 0.000000] ssb: Core 5 found: MIPS 3302 (cc 0x816, rev 0x03, vendor
0x4243)
[ 0.000000] ssb: Core 6 found: V90 (cc 0x807, rev 0x02, vendor 0x4243)
[ 0.000000] ssb: Core 7 found: IPSEC (cc 0x80B, rev 0x00, vendor
0x4243)
[ 0.000000] ssb: Core 8 found: MEMC SDRAM (cc 0x80F, rev 0x02, vendor
0x4243)
[ 0.000000] ssb: chipcommon status is 0x0
[ 0.000000] ssb: Initializing MIPS core...
[ 0.000000] ssb: set_irq: core 0x0806, irq 4 => 4
[ 0.000000] ssb: set_irq: core 0x0806, irq 5 => 5
[ 0.000000] ssb: set_irq: core 0x0808, irq 6 => 2
[ 0.000000] ssb: set_irq: core 0x0804, irq 2 => 6
[ 0.000000] ssb: after irq reconfiguration
[ 0.000000] ssb: core 0x0800, irq : 2(S) 3* 4 5 6 D I
[ 0.000000] ssb: core 0x0806, irq : 2(S) 3 4* 5 6 D I
[ 0.000000] ssb: core 0x0806, irq : 2(S) 3 4 5* 6 D I
[ 0.000000] ssb: core 0x0808, irq : 2(S)* 3 4 5 6 D I
[ 0.000000] ssb: core 0x0804, irq : 2(S) 3 4 5 6* D I
[ 0.000000] ssb: core 0x0816, irq : 2(S)* 3 4 5 6 D I
[ 0.000000] ssb: core 0x0807, irq : 2(S)* 3 4 5 6 D I
[ 0.000000] ssb: core 0x080b, irq : 2(S)* 3 4 5 6 D I
[ 0.000000] ssb: core 0x080f, irq : 2(S) 3 4 5 6 D I*
[ 0.000000] found parallel flash.
[ 0.000000] Unsupported SPROM revision 0 detected. Will extract v1
[ 0.000000] ssb: Sonics Silicon Backplane found at address 0x18000000
[ 0.000000] Serial init done.
[ 0.000000] gpiochip_add: registered GPIOs 0 to 15 on device: bcm47xx
[ 0.000000] Determined physical RAM map:
[ 0.000000] memory: 02000000 @ 00000000 (usable)
[ 0.000000] Initrd not found or empty - disabling initrd
[ 0.000000] Zone PFN ranges:
[ 0.000000] Normal 0x00000000 -> 0x00002000
[ 0.000000] Movable zone start PFN for each node
[ 0.000000] Early memory PFN ranges
[ 0.000000] 0: 0x00000000 -> 0x00002000
[ 0.000000] On node 0 totalpages: 8192
[ 0.000000] free_area_init_node: node 0, pgdat 802da6d0, node_mem_map
81000000
[ 0.000000] Normal zone: 64 pages used for memmap
[ 0.000000] Normal zone: 0 pages reserved
[ 0.000000] Normal zone: 8128 pages, LIFO batch:0
[ 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: 8128
[ 0.000000] Kernel command line: root=/dev/mtdblock2
rootfstype=squashfs,jffs2 noinitrd console=ttyS0,115200
[ 0.000000] PID hash table entries: 128 (order: -3, 512 bytes)
[ 0.000000] Dentry cache hash table entries: 4096 (order: 2, 16384
bytes)
[ 0.000000] Inode-cache hash table entries: 2048 (order: 1, 8192 bytes)
[ 0.000000] Primary instruction cache 16kB, VIPT, 2-way, linesize 16
bytes.
[ 0.000000] Primary data cache 16kB, 2-way, VIPT, cache aliases,
linesize 16 bytes
[ 0.000000] Memory: 29100k/32768k available (2540k kernel code, 3668k
reserved, 379k data, 168k init, 0k highmem)
[ 0.000000] NR_IRQS:128
[ 0.000000] console [ttyS0] enabled
[ 0.004000] Calibrating delay loop... 262.14 BogoMIPS (lpj=524288)
[ 0.048000] pid_max: default: 32768 minimum: 301
[ 0.052000] Mount-cache hash table entries: 512
[ 0.060000] NET: Registered protocol family 16
[ 0.096000] bio: create slab <bio-0> at 0
[ 0.108000] Switching to clocksource MIPS
[ 0.124000] ssb: PCIcore in host mode found
[ 0.432000] PCI host bridge to bus 0000:00
[ 0.436000] pci_bus 0000:00: root bus resource [mem
0x40000000-0x7fffffff]
[ 0.444000] pci_bus 0000:00: root bus resource [io 0x0100-0x07ff]
[ 0.448000] pci 0000:00:00.0: [14e4:4704] type 0 class 0x000600
[ 0.448000] PCI: Fixing up bridge 0000:00:00.0
[ 0.452000] pci 0000:00:00.0: setting latency timer to 64
[ 0.452000] PCI: Fixing up device 0000:00:00.0
[ 0.460000] PCI: Fixing latency timer of device 0000:00:00.0 to 168
[ 0.464000] pci 0000:00:00.0: reg 10: [mem 0x00000000-0x00001fff]
[ 0.464000] pci 0000:00:00.0: reg 14: [mem 0x00000000-0x07ffffff pref]
[ 0.464000] pci 0000:00:02.0: [14e4:4329] type 0 class 0x000280
[ 0.464000] pci 0000:00:02.0: reg 10: [mem 0x00000000-0x00003fff]
[ 0.464000] pci 0000:00:03.0: [1106:3038] type 0 class 0x000c03
[ 0.464000] pci 0000:00:03.0: reg 20: [io 0xfce0-0xfcff]
[ 0.464000] pci 0000:00:03.0: supports D1 D2
[ 0.464000] pci 0000:00:03.0: PME# supported from D0 D1 D2 D3hot
[ 0.468000] pci 0000:00:03.1: [1106:3038] type 0 class 0x000c03
[ 0.468000] pci 0000:00:03.1: reg 20: [io 0xfce0-0xfcff]
[ 0.468000] pci 0000:00:03.1: supports D1 D2
[ 0.468000] pci 0000:00:03.1: PME# supported from D0 D1 D2 D3hot
[ 0.468000] pci 0000:00:03.2: [1106:3104] type 0 class 0x000c03
[ 0.468000] pci 0000:00:03.2: reg 10: [mem 0x00000000-0x000000ff]
[ 0.468000] pci 0000:00:03.2: supports D1 D2
[ 0.468000] pci 0000:00:03.2: PME# supported from D0 D1 D2 D3hot
[ 0.472000] pci 0000:00:02.0: BAR 0: assigned [mem
0x40000000-0x40003fff]
[ 0.480000] pci 0000:00:03.2: BAR 0: assigned [mem
0x40004000-0x400040ff]
[ 0.488000] pci 0000:00:03.0: BAR 4: assigned [io 0x0400-0x041f]
[ 0.492000] pci 0000:00:03.1: BAR 4: assigned [io 0x0420-0x043f]
[ 0.504000] PCI: Enabling device 0000:00:02.0 (0000 -> 0002)
[ 0.512000] PCI: Fixing up device 0000:00:02.0
[ 0.516000] b43-pci-bridge 0000:00:02.0: setting latency timer to 64
[ 0.536000] ssb: Found chip with id 0x4321, rev 0x01 and package 0x01
[ 0.540000] ssb: Core 0 found: ChipCommon (cc 0x800, rev 0x12, vendor
0x4243)
[ 0.540000] ssb: Core 1 found: IEEE 802.11 (cc 0x812, rev 0x0B, vendor
0x4243)
[ 0.540000] ssb: Core 2 found: PCI-E (cc 0x820, rev 0x02, vendor
0x4243)
[ 0.540000] ssb: Core 3 found: PCI (cc 0x804, rev 0x0D, vendor 0x4243)
[ 0.540000] ssb: Core 4 found: USB 1.1 Host (cc 0x817, rev 0x04, vendor
0x4243)
[ 0.560000] ssb: chipcommon status is 0x0
[ 0.564000] ssb: SPROM offset is 0x1000
[ 0.584000] ssb: SPROM revision 4 detected.
[ 0.604000] ssb: Sonics Silicon Backplane found on PCI device
0000:00:02.0
[ 0.616000] NET: Registered protocol family 2
[ 0.620000] IP route cache hash table entries: 1024 (order: 0, 4096
bytes)
[ 0.628000] TCP established hash table entries: 1024 (order: 1, 8192
bytes)
[ 0.636000] TCP bind hash table entries: 1024 (order: 0, 4096 bytes)
[ 0.644000] TCP: Hash tables configured (established 1024 bind 1024)
[ 0.648000] TCP reno registered
[ 0.652000] UDP hash table entries: 256 (order: 0, 4096 bytes)
[ 0.660000] UDP-Lite hash table entries: 256 (order: 0, 4096 bytes)
[ 0.668000] NET: Registered protocol family 1
[ 0.672000] PCI: CLS 0 bytes, default 16
[ 0.680000] squashfs: version 4.0 (2009/01/31) Phillip Lougher
[ 0.688000] JFFS2 version 2.2 (NAND) (SUMMARY) (LZMA) (RTIME)
(CMODE_PRIORITY) (c) 2001-2006 Red Hat, Inc.
[ 0.696000] msgmni has been set to 56
[ 0.704000] Block layer SCSI generic (bsg) driver version 0.4 loaded
(major 254)
[ 0.708000] io scheduler noop registered
[ 0.712000] io scheduler deadline registered (default)
[ 0.720000] Serial: 8250/16550 driver, 16 ports, IRQ sharing enabled
[ 0.748000] serial8250: ttyS0 at MMIO 0xb8000300 (irq = 3) is a 16550A
[ 0.776000] serial8250: ttyS1 at MMIO 0xb8000400 (irq = 3) is a 16550A
[ 0.820000] serial8250.0: ttyS0 at MMIO 0xb8000300 (irq = 3) is a
16550A
[ 0.848000] serial8250.0: ttyS1 at MMIO 0xb8000400 (irq = 3) is a
16550A
[ 0.860000] bcm47xx_pflash: flash init: 0x1c000000 0x02000000
[ 0.868000] Physically mapped flash: Found 1 x16 devices at 0x0 in
16-bit bank. Manufacturer ID 0x000001 Chip ID 0x001301
[ 0.880000] Physically mapped flash: Found an alias at 0x800000 for the
chip at 0x0
[ 0.880000] Physically mapped flash: Found an alias at 0x1000000 for
the chip at 0x0
[ 0.880000] Physically mapped flash: Found an alias at 0x1800000 for
the chip at 0x0
[ 0.880000] Amd/Fujitsu Extended Query Table at 0x0040
[ 0.884000] Amd/Fujitsu Extended Query version 1.3.
[ 0.888000] number of CFI chips: 1
[ 0.892000] bcm47xx_pflash: Flash device: 0x2000000 at 0x1fc00000
[ 0.900000] bcm47xx_part: bootloader size: 262144
[ 0.908000] bcm47xx_part: Looking for dual image
[ 0.920000] bcm47xx_part: TRX offset : 0
[ 0.924000] 4 bcm47xx partitions found on MTD device Physically mapped
flash
[ 0.932000] Creating 4 MTD partitions on "Physically mapped flash":
[ 0.936000] 0x000000000000-0x000000040000 : "cfe"
[ 0.948000] 0x000000040000-0x0000007f0000 : "linux"
[ 0.960000] 0x000000133c00-0x0000007f0000 : "rootfs"
[ 0.964000] mtd: partition "rootfs" must either start or end on erase
block boundary or be smaller than an erase block -- forcing read-only
[ 0.980000] mtd: partition "rootfs" set to be root filesystem
[ 0.988000] mtd: partition "rootfs_data" created automatically,
ofs=310000, len=4E0000
[ 0.996000] 0x000000310000-0x0000007f0000 : "rootfs_data"
[ 1.008000] 0x0000007f0000-0x000000800000 : "nvram"
[ 1.020000] bcm47xx_sflash: error registering platform driver: -19
[ 1.028000] bcm47xx_nflash: error registering platform driver: -19
[ 1.036000] b44: Broadcom 44xx/47xx 10/100 PCI ethernet driver version
2.0
[ 1.048000] b44 ssb0:0: eth0: Broadcom 44xx/47xx 10/100 PCI ethernet
driver 00:22:15:04:03:1a
[ 1.060000] b44 ssb0:1: eth1: PHY Reset would not complete
[ 1.068000] b44 ssb0:1: eth1: Broadcom 44xx/47xx 10/100 PCI ethernet
driver 00:90:4c:a1:00:2d
[ 1.076000] BCM47xx Watchdog Timer enabled (30 seconds, nowayout)
[ 1.084000] TCP cubic registered
[ 1.088000] NET: Registered protocol family 17
[ 1.092000] Bridge firewalling registered
[ 1.100000] 8021q: 802.1Q VLAN Support v1.8
[ 1.116000] VFS: Mounted root (squashfs filesystem) readonly on device
31:2.
[ 1.124000] Freeing unused kernel memory: 168k freed
[ 4.344000] diag: Detected 'ASUS WL-500W'
[ 4.468000] b44 ssb0:0: eth0: Link is up at 100 Mbps, full duplex
[ 4.476000] b44 ssb0:0: eth0: Flow control is off for TX and off for RX
[ 5.872000] roboswitch: Probing device eth0: found a 5325! It's a 5350.
[ 13.956000] JFFS2 notice: (353) jffs2_build_xattr_subsystem: complete
building xattr subsystem, 1 of xdatum (1 unchecked, 0 orphan) and 53 of
xref (0 dead, 6 orphan) found.
[ 14.680000] b44 ssb0:0: eth0: powering down PHY
[ 20.412000] wl: module license 'unspecified' taints kernel.
[ 20.416000] Disabling lock debugging due to kernel taint
[ 20.512000] SSB/BCMA glue driver successfully attached
[ 20.516000] PCI: Fixing up device 0000:00:02.0
[ 20.628000] wl0: Broadcom BCM4329 802.11 Wireless Controller 5.10.56.27
[ 20.852000] PPP generic driver version 2.4.2
[ 21.104000] ip_tables: (C) 2000-2006 Netfilter Core Team
[ 21.376000] NET: Registered protocol family 24
[ 21.420000] nf_conntrack version 0.5.0 (457 buckets, 1828 max)
[ 25.140000] b44 ssb0:0: eth0: Link is up at 100 Mbps, full duplex
[ 25.144000] b44 ssb0:0: eth0: Flow control is off for TX and off for RX
[ 28.792000] b44 ssb0:0: eth0: powering down PHY
[ 28.880000] b44 ssb0:0: eth0: Link is up at 100 Mbps, full duplex
[ 28.888000] b44 ssb0:0: eth0: Flow control is off for TX and off for RX
[ 29.096000] device eth0 entered promiscuous mode
[ 29.104000] br-lan: port 1(eth0) entered forwarding state
[ 29.108000] br-lan: port 1(eth0) entered forwarding state
[ 29.148000] b44 ssb0:1: eth1: Link is up at 100 Mbps, full duplex
[ 29.152000] b44 ssb0:1: eth1: Flow control is off for TX and off for RX
[ 31.112000] br-lan: port 1(eth0) entered forwarding state
[ 35.540000] device wl0 entered promiscuous mode
[ 35.544000] br-lan: port 2(wl0) entered forwarding state
[ 35.552000] br-lan: port 2(wl0) entered forwarding state
[ 37.556000] br-lan: port 2(wl0) entered forwarding state
[ 185.484000] br-lan: received packet on wl0 with own address as source
address
--
Note the
[ 1.060000] b44 ssb0:1: eth1: PHY Reset would not complete
[ 5.872000] roboswitch: Probing device eth0: found a 5325! It's a 5350.
[ 14.680000] b44 ssb0:0: eth0: powering down PHY
And logread has
Nov 29 16:21:37 Hostname kern.warn kernel: [ 595.008000] ------------[
cut here ]------------
Nov 29 16:21:37 Hostname kern.warn kernel: [ 595.012000] WARNING: at
net/sched/sch_generic.c:256 0x801e3fcc()
Nov 29 16:21:37 Hostname kern.info kernel: [ 595.016000] NETDEV WATCHDOG:
eth0 (b44): transmit queue 0 timed out
Nov 29 16:21:37 Hostname kern.warn kernel: [ 595.024000] Modules linked
in: nf_nat_irc nf_conntrack_irc nf_nat_ftp nf_conntrack_ftp ipt_MASQUERADE
iptable_nat nf_nat pppoe xt_conntrack xt_CT xt_NOTRACK iptable_raw
xt_state nf_conntrack_ipv4 nf_defrag_ipv4 nf_conntrack p
Nov 29 16:21:37 Hostname kern.warn kernel: [ 595.068000] Call
Trace:[<80274f8c>] 0x80274f8c
Nov 29 16:21:37 Hostname kern.warn kernel: [ 595.072000] [<80274f8c>]
0x80274f8c
Nov 29 16:21:37 Hostname kern.warn kernel: [ 595.076000] [<80017b80>]
0x80017b80
Nov 29 16:21:37 Hostname kern.warn kernel: [ 595.080000] [<801e3fcc>]
0x801e3fcc
Nov 29 16:21:37 Hostname kern.warn kernel: [ 595.084000] [<80017c34>]
0x80017c34
Nov 29 16:21:37 Hostname kern.warn kernel: [ 595.088000] [<800396c4>]
0x800396c4
Nov 29 16:21:37 Hostname kern.warn kernel: [ 595.092000] [<80e22ba0>]
0x80e22ba0
Nov 29 16:21:37 Hostname kern.warn kernel: [ 595.092000] [<801e3fcc>]
0x801e3fcc
Nov 29 16:21:37 Hostname kern.warn kernel: [ 595.096000] [<801e3dd4>]
0x801e3dd4
Nov 29 16:21:37 Hostname kern.warn kernel: [ 595.100000] [<80024474>]
0x80024474
Nov 29 16:21:37 Hostname kern.warn kernel: [ 595.104000] [<8001e914>]
0x8001e914
Nov 29 16:21:37 Hostname kern.warn kernel: [ 595.108000] [<8001eb80>]
0x8001eb80
Nov 29 16:21:37 Hostname kern.warn kernel: [ 595.112000] [<8005702c>]
0x8005702c
Nov 29 16:21:37 Hostname kern.warn kernel: [ 595.116000] [<8001edcc>]
0x8001edcc
Nov 29 16:21:37 Hostname kern.warn kernel: [ 595.120000] [<80001de0>]
0x80001de0
Nov 29 16:21:37 Hostname kern.warn kernel: [ 595.124000] [<80005b64>]
0x80005b64
Nov 29 16:21:37 Hostname kern.warn kernel: [ 595.128000] [<80005d80>]
0x80005d80
Nov 29 16:21:37 Hostname kern.warn kernel: [ 595.132000] [<8004177c>]
0x8004177c
Nov 29 16:21:37 Hostname kern.warn kernel: [ 595.132000] [<800077e8>]
0x800077e8
Nov 29 16:21:37 Hostname kern.warn kernel: [ 595.136000] [<800077e8>]
0x800077e8
Nov 29 16:21:37 Hostname kern.warn kernel: [ 595.140000] [<802db8fc>]
0x802db8fc
Nov 29 16:21:37 Hostname kern.warn kernel: [ 595.144000] [<802faf70>]
0x802faf70
Nov 29 16:21:37 Hostname kern.warn kernel: [ 595.148000] [<802db0dc>]
0x802db0dc
Nov 29 16:21:37 Hostname kern.warn kernel: [ 595.152000]
Nov 29 16:21:37 Hostname kern.warn kernel: [ 595.152000] ---[ end trace
e3f0030b8f285a97 ]---
Nov 29 16:21:37 Hostname kern.err kernel: [ 595.160000] b44 ssb0:0: eth0:
transmit timed out, resetting
Nov 29 16:21:37 Hostname kern.info kernel: [ 595.164000] b44 ssb0:0:
eth0: powering down PHY
And the router is automatically rebooting some time after that.
I have no idea what's going here. The hardware has been up and running for
years with DD-WRT, I just recently flashed OpenWRT hoping to be able to
tap into the great community.
--
Ticket URL: <https://dev.openwrt.org/ticket/18449>
OpenWrt <http://openwrt.org>
Opensource Wireless Router Technology
_______________________________________________
openwrt-tickets mailing list
[email protected]
https://lists.openwrt.org/cgi-bin/mailman/listinfo/openwrt-tickets
