Re: [Dev] [gluon] Looking for logs from TL-WR841 v5 with Gluon 2016.1 - Dev

22 Feb 2016


      Hi,
...
we've gotten reports that Gluon 2016.1 doesn't boot on TL-WR841 v5. I've
already found out that one of our patches on top of OpenWrt CC is the
culprit (probably one of the backports from the OpenWrt trunk).
Unfortunately, I don't have such a device to test, so if you do and and
are able to connect it to a serial console, getting logs of the crash
would be highly appreciated. I suspect the issue might also be
reproducible on the TL-WR741 v1/v2, TL-WR743 v1 and TL-WR941 v4, as the
hardware is very similar.
I got a TL-WR841 v5 device from Jens and I could got an serial dump.
The image is our v0.8 image based on gluon v2016.1.x
55dc9c369d763f4b359070b2628efe46d983e0ec
Should I try that experimental image from you?
--
Tarek
1. start
tata@keks ~ % picocom -b 115200 /dev/ttyUSB0
picocom v2.1
port is        : /dev/ttyUSB0
flowcontrol    : none
baudrate is    : 115200
parity is      : none
databits are   : 8
stopbits are   : 1
escape is      : C-a
local echo is  : no
noinit is      : no
noreset is     : no
nolock is      : no
send_cmd is    : sz -vv
receive_cmd is : rz -vv -E
imap is        :
omap is        :
emap is        : crcrlf,delbs,
Type [C-a] [C-h] to see available commands
Terminal ready
U-Boot 1.1.4 (Nov 16 2009 - 09:06:24)
AP93 (ar7240) U-boot
DRAM:
sri
#### TAP VALUE 1 = 8, 2 = 8
32 MB
id read 0x100000ff
flash size 4194304, sector count = 64
Flash:  4 MB
Using default environment
In:    serial
Out:   serial
Err:   serial
Net:   ag7240_enet_initialize...
No valid address in Flash. Using fixed address
: cfg1 0xf cfg2 0x7014
eth0: 00:03:7f:09:0b:ad
eth0 up
No valid address in Flash. Using fixed address
: cfg1 0xf cfg2 0x7214
eth1: 00:03:7f:09:0b:ad
ATHRS26: resetting s26
ATHRS26: s26 reset done
eth1 up
eth0, eth1
Autobooting in 1 seconds
## Booting image at 9f020000 ...
   Uncompressing Kernel Image ... OK
Starting kernel ...
OpenWrt kernel loader for AR7XXX/AR9XXX
Copyright (C) 2011 Gabor Juhos juhosg@openwrt.org
Looking for OpenWrt image... found at 0xbf022000
Decompressing kernel... done!
Starting kernel at 80060000...
[    0.000000] Linux version 3.18.23 (root@buildvm) (gcc version 4.8.3
(OpenWrt/Linaro GCC 4.8-2014.04 r47335) ) #2 Tue Feb 9 08:48:38 CET 2016
[    0.000000] bootconsole [early0] enabled
[    0.000000] CPU0 revision is: 00019374 (MIPS 24Kc)
[    0.000000] SoC: Atheros AR7240 rev 2
[    0.000000] Determined physical RAM map:
[    0.000000]  memory: 02000000 @ 00000000 (usable)
[    0.000000] Initrd not found or empty - disabling initrd
[    0.000000] Zone ranges:
[    0.000000]   Normal   [mem 0x00000000-0x01ffffff]
[    0.000000] Movable zone start for each node
[    0.000000] Early memory node ranges
[    0.000000]   node   0: [mem 0x00000000-0x01ffffff]
[    0.000000] Initmem setup node 0 [mem 0x00000000-0x01ffffff]
[    0.000000] Primary instruction cache 64kB, VIPT, 4-way, linesize 32
bytes.
[    0.000000] Primary data cache 32kB, 4-way, VIPT, cache aliases,
linesize 32 bytes
[    0.000000] Built 1 zonelists in Zone order, mobility grouping on.
Total pages: 8128
[    0.000000] Kernel command line:  board=TL-WR741ND
console=ttyS0,115200 rootfstype=squashfs,jffs2 noinitrd
[    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] Writing ErrCtl register=00000000
[    0.000000] Readback ErrCtl register=00000000
[    0.000000] Memory: 28324K/32768K available (2615K kernel code, 132K
rwdata, 548K rodata, 216K init, 194K bss, 4444K reserved)
[    0.000000] SLUB: HWalign=32, Order=0-3, MinObjects=0, CPUs=1, Nodes=1
[    0.000000] NR_IRQS:51
[    0.000000] Clocks: CPU:400.000MHz, DDR:400.000MHz, AHB:200.000MHz,
Ref:5.000MHz
[    0.000000] Calibrating delay loop... 265.42 BogoMIPS (lpj=1327104)
[    0.080000] pid_max: default: 32768 minimum: 301
[    0.080000] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes)
[    0.090000] Mountpoint-cache hash table entries: 1024 (order: 0, 4096
bytes)
[    0.100000] NET: Registered protocol family 16
[    0.100000] MIPS: machine is TP-LINK TL-WR741ND
[    0.550000] registering PCI controller with io_map_base unset
[    0.560000] PCI host bridge to bus 0000:00
[    0.570000] pci_bus 0000:00: root bus resource [mem
0x10000000-0x13ffffff]
[    0.570000] pci_bus 0000:00: root bus resource [io  0x0000]
[    0.580000] pci_bus 0000:00: No busn resource found for root bus,
will use [bus 00-ff]
[    0.580000] pci 0000:00:00.0: fixup device configuration
[    0.590000] pci 0000:00:00.0: BAR 0: assigned [mem
0x10000000-0x1000ffff 64bit]
[    0.590000] pci 0000:00:00.0: using irq 40 for pin 1
[    0.600000] Switched to clocksource MIPS
[    0.600000] NET: Registered protocol family 2
[    0.610000] TCP established hash table entries: 1024 (order: 0, 4096
bytes)
[    0.610000] TCP bind hash table entries: 1024 (order: 0, 4096 bytes)
[    0.620000] TCP: Hash tables configured (established 1024 bind 1024)
[    0.630000] TCP: reno registered
[    0.630000] UDP hash table entries: 256 (order: 0, 4096 bytes)
[    0.640000] UDP-Lite hash table entries: 256 (order: 0, 4096 bytes)
[    0.640000] NET: Registered protocol family 1
[    0.650000] futex hash table entries: 256 (order: -1, 3072 bytes)
[    0.660000] squashfs: version 4.0 (2009/01/31) Phillip Lougher
[    0.670000] jffs2: version 2.2 (NAND) (SUMMARY) (LZMA) (RTIME)
(CMODE_PRIORITY) (c) 2001-2006 Red Hat, Inc.
[    0.680000] msgmni has been set to 55
[    0.680000] io scheduler noop registered
[    0.690000] io scheduler deadline registered (default)
[    0.690000] Serial: 8250/16550 driver, 16 ports, IRQ sharing enabled
[    0.700000] console [ttyS0] disabled
19. start
U-Boot 1.1.4 (Nov 16 2009 - 09:06:24)
AP93 (ar7240) U-boot
DRAM:
sri
#### TAP VALUE 1 = 7, 2 = 8
32 MB
id read 0x100000ff
flash size 4194304, sector count = 64
Flash:  4 MB
Using default environment
In:    serial
Out:   serial
Err:   serial
Net:   ag7240_enet_initialize...
No valid address in Flash. Using fixed address
: cfg1 0xf cfg2 0x7014
eth0: 00:03:7f:09:0b:ad
eth0 up
No valid address in Flash. Using fixed address
: cfg1 0xf cfg2 0x7214
eth1: 00:03:7f:09:0b:ad
ATHRS26: resetting s26
ATHRS26: s26 reset done
eth1 up
eth0, eth1
Autobooting in 1 seconds
## Booting image at 9f020000 ...
   Uncompressing Kernel Image ... OK
Starting kernel ...
OpenWrt kernel loader for AR7XXX/AR9XXX
Copyright (C) 2011 Gabor Juhos juhosg@openwrt.org
Looking for OpenWrt image... found at 0xbf022000
Decompressing kernel... done!
Starting kernel at 80060000...
[    0.000000] Linux version 3.18.23 (root@buildvm) (gcc version 4.8.3
(OpenWrt/Linaro GCC 4.8-2014.04 r47335) ) #2 Tue Feb 9 08:48:38 CET 2016
[    0.000000] bootconsole [early0] enabled
[    0.000000] CPU0 revision is: 00019374 (MIPS 24Kc)
[    0.000000] SoC: Atheros AR7240 rev 2
[    0.000000] Determined physical RAM map:
[    0.000000]  memory: 02000000 @ 00000000 (usable)
[    0.000000] Initrd not found or empty - disabling initrd
[    0.000000] Zone ranges:
[    0.000000]   Normal   [mem 0x00000000-0x01ffffff]
[    0.000000] Movable zone start for each node
[    0.000000] Early memory node ranges
[    0.000000]   node   0: [mem 0x00000000-0x01ffffff]
[    0.000000] Initmem setup node 0 [mem 0x00000000-0x01ffffff]
[    0.000000] Primary instruction cache 64kB, VIPT, 4-way, linesize 32
bytes.
[    0.000000] Primary data cache 32kB, 4-way, VIPT, cache aliases,
linesize 32 bytes
[    0.000000] Built 1 zonelists in Zone order, mobility grouping on.
Total pages: 8128
[    0.000000] Kernel command line:  board=TL-WR741ND
console=ttyS0,115200 rootfstype=squashfs,jffs2 noinitrd
[    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] Writing ErrCtl register=00000000
[    0.000000] Readback ErrCtl register=00000000
[    0.000000] Memory: 28324K/32768K available (2615K kernel code, 132K
rwdata, 548K rodata, 216K init, 194K bss, 4444K reserved)
[    0.000000] SLUB: HWalign=32, Order=0-3, MinObjects=0, CPUs=1, Nodes=1
[    0.000000] NR_IRQS:51
[    0.000000] Clocks: CPU:400.000MHz, DDR:400.000MHz, AHB:200.000MHz,
Ref:5.000MHz
[    0.000000] Calibrating delay loop... 265.42 BogoMIPS (lpj=1327104)
[    0.080000] pid_max: default: 32768 minimum: 301
[    0.080000] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes)
[    0.090000] Mountpoint-cache hash table entries: 1024 (order: 0, 4096
bytes)
[    0.100000] NET: Registered protocol family 16
[    0.100000] MIPS: machine is TP-LINK TL-WR741ND
[    0.550000] registering PCI controller with io_map_base unset
[    0.560000] PCI host bridge to bus 0000:00
[    0.570000] pci_bus 0000:00: root bus resource [mem
0x10000000-0x13ffffff]
[    0.570000] pci_bus 0000:00: root bus resource [io  0x0000]
[    0.580000] pci_bus 0000:00: No busn resource found for root bus,
will use [bus 00-ff]
[    0.580000] pci 0000:00:00.0: fixup device configuration
[    0.590000] pci 0000:00:00.0: BAR 0: assigned [mem
0x10000000-0x1000ffff 64bit]
[    0.590000] pci 0000:00:00.0: using irq 40 for pin 1
[    0.600000] Switched to clocksource MIPS
[    0.600000] NET: Registered protocol family 2
[    0.610000] TCP established hash table entries: 1024 (order: 0, 4096
bytes)
[    0.610000] TCP bind hash table entries: 1024 (order: 0, 4096 bytes)
[    0.620000] TCP: Hash tables configured (established 1024 bind 1024)
[    0.630000] TCP: reno registered
[    0.630000] UDP hash table entries: 256 (order: 0, 4096 bytes)
[    0.640000] UDP-Lite hash table entries: 256 (order: 0, 4096 bytes)
[    0.640000] NET: Registered protocol family 1
[    0.650000] futex hash table entries: 256 (order: -1, 3072 bytes)
[    0.660000] squashfs: version 4.0 (2009/01/31) Phillip Lougher
[    0.670000] jffs2: version 2.2 (NAND) (SUMMARY) (LZMA) (RTIME)
(CMODE_PRIORITY) (c) 2001-2006 Red Hat, Inc.
[    0.680000] msgmni has been set to 55
[    0.680000] io scheduler noop registered
[    0.690000] io scheduler deadline registered (default)
[    0.690000] Serial: 8250/16550 driver, 16 ports, IRQ sharing enabled
[    0.700000] console [ttyS0] disabled
[    0.730000] serial8250.0: ttyS0 at MMIO 0x18020000 (irq = 11,
base_baud = 12500000) is a 16550A
[    0.740000] console [ttyS0] enabled
[    0.740000] console [ttyS0] enabled
[    0.740000] bootconsole [early0] disabled
[    0.740000] bootconsole [early0] disabled
[    0.750000] m25p80 spi0.0: found s25sl032p, expected m25p80
[    0.760000] m25p80 spi0.0: s25sl032p (4096 Kbytes)
[    0.760000] 5 tp-link partitions found on MTD device spi0.0
[    0.770000] Creating 5 MTD partitions on "spi0.0":
[    0.780000] 0x000000000000-0x000000020000 : "u-boot"
[    0.780000] 0x000000020000-0x000000144e00 : "kernel"
[    0.790000] 0x000000144e00-0x0000003f0000 : "rootfs"
[    0.790000] mtd: device 2 (rootfs) set to be root filesystem
[    0.800000] 1 squashfs-split partitions found on MTD device rootfs
[    0.810000] 0x000000390000-0x0000003f0000 : "rootfs_data"
[    0.810000] 0x0000003f0000-0x000000400000 : "art"
[    0.820000] 0x000000020000-0x0000003f0000 : "firmware"
[    0.870000] libphy: ag71xx_mdio: probed
[    1.460000] ag71xx-mdio.0: Found an AR7240/AR9330 built-in switch
[    1.500000] eth0: Atheros AG71xx at 0xba000000, irq 5, mode:GMII
[    2.090000] ag71xx ag71xx.0: connected to PHY at ag71xx-mdio.0:04
[uid=004dd041, driver=Generic PHY]
[    2.100000] eth1: Atheros AG71xx at 0xb9000000, irq 4, mode:MII
[    2.110000] TCP: cubic registered
[    2.110000] NET: Registered protocol family 17
[    2.120000] bridge: automatic filtering via arp/ip/ip6tables has been
deprecated. Update your scripts to load br_netfilter if you need this.
[    2.130000] Bridge firewalling registered
[    2.130000] 8021q: 802.1Q VLAN Support v1.8
[    2.150000] VFS: Mounted root (squashfs filesystem) readonly on
device 31:2.
[    2.160000] Freeing unused kernel memory: 216K (8039a000 - 803d0000)
[    3.540000] init: Console is alive
[    3.540000] init: - watchdog -
[    5.650000] init: - preinit -
[    6.520000] random: procd urandom read with 10 bits of entropy available
Press the [f] key and hit [enter] to enter failsafe mode
Press the [1], [2], [3] or [4] key and hit [enter] to select the debug level
[    8.590000] eth0: link up (1000Mbps/Full duplex)
[    9.910000] mount_root: jffs2 not ready yet, using temporary tmpfs
overlay
[   10.180000] eth0: link down
[   10.190000] procd: - early -
[   10.190000] procd: - watchdog -
[   10.990000] procd: - ubus -
[   12.000000] procd: - init -
Please press Enter to activate this console.
[   13.250000] NET: Registered protocol family 10
[   13.260000] tun: Universal TUN/TAP device driver, 1.6
[   13.270000] tun: (C) 1999-2004 Max Krasnyansky maxk@qualcomm.com
[   13.280000] ip6_tables: (C) 2000-2006 Netfilter Core Team
[   13.310000] batman_adv: B.A.T.M.A.N. advanced 2015.1 (compatibility
version 15) loaded
[   13.330000] u32 classifier
[   13.330000]     input device check on
[   13.330000]     Actions configured
[   13.340000] Mirror/redirect action on
[   13.350000] netem: version 1.3
[   13.390000] Loading modules backported from Linux version
master-2015-07-21-0-g47cd203
[   13.400000] Backport generated by backports.git
backports-20150626-0-gc1a4168
[   13.440000] Ebtables v2.0 registered
[   13.450000] ip_tables: (C) 2000-2006 Netfilter Core Team
[   13.470000] nf_conntrack version 0.5.0 (445 buckets, 1780 max)
[   13.570000] xt_time: kernel timezone is -0000
[   13.640000] cfg80211: World regulatory domain updated:
[   13.640000] cfg80211:  DFS Master region: unset
[   13.650000] cfg80211:   (start_freq - end_freq @ bandwidth),
(max_antenna_gain, max_eirp), (dfs_cac_time)
[   13.660000] cfg80211:   (2402000 KHz - 2472000 KHz @ 40000 KHz),
(N/A, 2000 mBm), (N/A)
[   13.670000] cfg80211:   (2457000 KHz - 2482000 KHz @ 40000 KHz),
(N/A, 2000 mBm), (N/A)
[   13.670000] cfg80211:   (2474000 KHz - 2494000 KHz @ 20000 KHz),
(N/A, 2000 mBm), (N/A)
[   13.680000] cfg80211:   (5170000 KHz - 5250000 KHz @ 80000 KHz,
160000 KHz AUTO), (N/A, 2000 mBm), (N/A)
[   13.690000] cfg80211:   (5250000 KHz - 5330000 KHz @ 80000 KHz,
160000 KHz AUTO), (N/A, 2000 mBm), (0 s)
[   13.700000] cfg80211:   (5490000 KHz - 5730000 KHz @ 160000 KHz),
(N/A, 2000 mBm), (0 s)
[   13.710000] cfg80211:   (5735000 KHz - 5835000 KHz @ 80000 KHz),
(N/A, 2000 mBm), (N/A)
[   13.720000] cfg80211:   (57240000 KHz - 63720000 KHz @ 2160000 KHz),
(N/A, 0 mBm), (N/A)
[   13.920000] PCI: Enabling device 0000:00:00.0 (0000 -> 0002)
[   13.950000] ieee80211 phy0: Atheros AR9280 Rev:2 mem=0xb0000000, irq=40
[   13.960000] cfg80211: Regulatory domain changed to country: US
[   13.970000] cfg80211:  DFS Master region: FCC
[   13.970000] cfg80211:   (start_freq - end_freq @ bandwidth),
(max_antenna_gain, max_eirp), (dfs_cac_time)
[   13.980000] cfg80211:   (2402000 KHz - 2472000 KHz @ 40000 KHz),
(N/A, 3000 mBm), (N/A)
[   13.990000] cfg80211:   (5170000 KHz - 5250000 KHz @ 80000 KHz,
160000 KHz AUTO), (N/A, 1700 mBm), (N/A)
[   14.000000] cfg80211:   (5250000 KHz - 5330000 KHz @ 80000 KHz,
160000 KHz AUTO), (N/A, 2300 mBm), (0 s)
[   14.010000] cfg80211:   (5490000 KHz - 5730000 KHz @ 160000 KHz),
(N/A, 2300 mBm), (0 s)
[   14.020000] cfg80211:   (5735000 KHz - 5835000 KHz @ 80000 KHz),
(N/A, 3000 mBm), (N/A)
[   14.030000] cfg80211:   (57240000 KHz - 63720000 KHz @ 2160000 KHz),
(N/A, 4000 mBm), (N/A)
[   26.930000] cfg80211: Regulatory domain changed to country: DE
[   26.930000] cfg80211:  DFS Master region: ETSI
[   26.940000] cfg80211:   (start_freq - end_freq @ bandwidth),
(max_antenna_gain, max_eirp), (dfs_cac_time)
[   26.950000] cfg80211:   (2400000 KHz - 2483000 KHz @ 40000 KHz),
(N/A, 2000 mBm), (N/A)
[   26.950000] cfg80211:   (5150000 KHz - 5250000 KHz @ 80000 KHz,
200000 KHz AUTO), (N/A, 2000 mBm), (N/A)
[   26.960000] cfg80211:   (5250000 KHz - 5350000 KHz @ 80000 KHz,
200000 KHz AUTO), (N/A, 2000 mBm), (0 s)
[   26.970000] cfg80211:   (5470000 KHz - 5725000 KHz @ 160000 KHz),
(N/A, 2700 mBm), (0 s)
[   26.980000] cfg80211:   (57000000 KHz - 66000000 KHz @ 2160000 KHz),
(N/A, 4000 mBm), (N/A)
[   30.390000] jffs2_scan_eraseblock(): End of filesystem marker found
at 0x0
[   30.390000] jffs2_build_filesystem(): unlocking the mtd device... done.
[   30.400000] jffs2_build_filesystem(): erasing all blocks after the
end marker... done.
[   32.190000] jffs2: notice: (1127) jffs2_build_xattr_subsystem:
complete building xattr subsystem, 0 of xdatum (0 unchecked, 0 orphan)
and 0 of xref (0 dead, 0 orphan) found.
[   35.210000] device eth0 entered promiscuous mode
[   35.220000] IPv6: ADDRCONF(NETDEV_UP): br-setup: link is not ready
[   36.810000] eth0: link up (1000Mbps/Full duplex)
[   36.810000] br-setup: port 1(eth0) entered forwarding state
[   36.820000] br-setup: port 1(eth0) entered forwarding state
[   36.830000] IPv6: ADDRCONF(NETDEV_CHANGE): br-setup: link becomes ready
[   38.820000] br-setup: port 1(eth0) entered forwarding state
[   69.750000] random: nonblocking pool is initialized
Please press Enter to activate this console.
BusyBox v1.23.2 (2016-02-09 03:32:42 CET) built-in shell (ash)
_______              __   ___               __
|    ___|.----.-----.|__|.'  _|.--.--.-----.|  |--.
|    ___||   _|  -__||  ||   _||  |  |     ||    <
|___|    |__| |_____||__||__|  |_____|__|__||__|__|
 _______                __ Freie Netze Fuer alle! __
|    |  |.-----.----.--|  |.--.--.--.-----.-----.|  |_
|       ||  _  |   _|  _  ||  |  |  |  -__|__ --||   _|
|__|____||_____|__| |_____||________|_____|_____||____|
#######################################################
  _______                     ________        __
 |       |.-----.-----.-----.|  |  |  |.----.|  |_
 |   -   ||  _  |  -__|     ||  |  |  ||   _||   _|
 |_______||   __|_____|__|__||________||__|  |____|
          |__| W I R E L E S S   F R E E D O M
 -----------------------------------------------------
  CHAOS CALMER (Chaos Calmer, r47335)
 -----------------------------------------------------
root@ffnw-d85d4c9ce280:/#