Modify

Opened 2 years ago

Last modified 2 years ago

#21319 new defect

TP-Link TD-W8970 VSDL problem

Reported by: d.buffa@… Owned by: developers
Priority: high Milestone: Designated Driver (Trunk)
Component: base system Version: Trunk
Keywords: Cc:

Description

Hi,

I had TD-W8970 working on my VDSL2 line for months with a trunk build version (i don't remember what), because I had a problem I build DD trunk and now DSL doesn't sync anymore.

[    0.000000] Linux version 4.1.13 (daniele@danfedvm.localdomain) (gcc version 5.2.0 (OpenWrt GCC 5.2.0 r47680) ) #1 Fri Dec 4 19:20:08 CET 2015
[    0.000000] SoC: VR9 rev 1.2
[    0.000000] bootconsole [early0] enabled
[    0.000000] CPU0 revision is: 00019556 (MIPS 34Kc)
[    0.000000] MIPS: machine is TDW8970 - TP-LINK TD-W8970
[    0.000000] Determined physical RAM map:
[    0.000000]  memory: 04000000 @ 00000000 (usable)
[    0.000000] Initrd not found or empty - disabling initrd
[    0.000000] Zone ranges:
[    0.000000]   Normal   [mem 0x0000000000000000-0x0000000003ffffff]
[    0.000000] Movable zone start for each node
[    0.000000] Early memory node ranges
[    0.000000]   node   0: [mem 0x0000000000000000-0x0000000003ffffff]
[    0.000000] Initmem setup node 0 [mem 0x0000000000000000-0x0000000003ffffff]
[    0.000000] On node 0 totalpages: 16384
[    0.000000] free_area_init_node: node 0, pgdat 804b1e00, node_mem_map 81007cc0
[    0.000000]   Normal zone: 128 pages used for memmap
[    0.000000]   Normal zone: 0 pages reserved
[    0.000000]   Normal zone: 16384 pages, LIFO batch:3
[    0.000000] Primary instruction cache 32kB, VIPT, 4-way, linesize 32 bytes.
[    0.000000] Primary data cache 32kB, 4-way, VIPT, cache aliases, linesize 32 bytes
[    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: 16256
[    0.000000] Kernel command line: console=ttyLTQ0,115200 init=/etc/preinit
[    0.000000] PID hash table entries: 256 (order: -2, 1024 bytes)
[    0.000000] Dentry cache hash table entries: 8192 (order: 3, 32768 bytes)
[    0.000000] Inode-cache hash table entries: 4096 (order: 2, 16384 bytes)
[    0.000000] Writing ErrCtl register=00068a20
[    0.000000] Readback ErrCtl register=00068a20
[    0.000000] Memory: 59556K/65536K available (3771K kernel code, 150K rwdata, 988K rodata, 188K init, 207K bss, 5980K reserved, 0K cma-reserved)
[    0.000000] NR_IRQS:256
[    0.000000] CPU Clock: 500MHz
[    0.000000] clocksource MIPS: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041786 ns
[    0.000011] sched_clock: 32 bits at 250MHz, resolution 4ns, wraps every 8589934590ns
[    0.008194] Calibrating delay loop... 332.54 BogoMIPS (lpj=665088)
[    0.042319] pid_max: default: 32768 minimum: 301
[    0.047313] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes)
[    0.053785] Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes)
[    0.066741] clocksource jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns
[    0.076456] pinctrl core: initialized pinctrl subsystem
[    0.082282] NET: Registered protocol family 16
[    0.091194] pinctrl-xway 1e100b10.pinmux: Init done
[    0.096720] dma-xway 1e104100.dma: Init done - hw rev: 7, ports: 7, channels: 28
[    0.207064] dcdc-xrx200 1f106a00.dcdc: Core Voltage : 1016 mV
[    0.324947] ath9k,eeprom ath9k_eep: failed to load eeprom address
[    0.353008] usbcore: registered new interface driver usbfs
[    0.358497] usbcore: registered new interface driver hub
[    0.363860] usbcore: registered new device driver usb
[    0.369221] PCI host bridge to bus 0000:00
[    0.373209] pci_bus 0000:00: root bus resource [mem 0x1c000000-0x1cffffff]
[    0.380125] pci_bus 0000:00: root bus resource [io  0x1d800000-0x1d8fffff]
[    0.387068] pci_bus 0000:00: root bus resource [??? 0x00000000 flags 0x0]
[    0.393924] pci_bus 0000:00: No busn resource found for root bus, will use [bus 00-ff]
[    0.401948] pci 0000:00:00.0: [1bef:0011] type 01 class 0x060000
[    0.401972] ifx_pcie_rc_class_early_fixup: fixed pcie host bridge to pci-pci bridge
[    0.419709] pci 0000:00:00.0: PME# supported from D0 D3hot
[    0.420220] pci 0000:01:00.0: [168c:abcd] type 00 class 0x020000
[    0.420288] pci 0000:01:00.0: reg 0x10: [mem 0x00000000-0x0001ffff 64bit]
[    0.420359] pci 0000:01:00.0: reg 0x30: [mem 0x00000000-0x0000ffff pref]
[    0.420452] pci 0000:01:00.0: supports D1
[    0.420473] pci 0000:01:00.0: PME# supported from D0 D1 D3hot
[    0.420752] pci_bus 0000:01: busn_res: [bus 01-ff] end is updated to 01
[    0.420789] pci_bus 0000:00: busn_res: [bus 00-ff] end is updated to 01
[    0.420847] pci 0000:00:00.0: BAR 8: assigned [mem 0x1c000000-0x1c0fffff]
[    0.427526] pci 0000:00:00.0: BAR 9: assigned [mem 0x1c100000-0x1c1fffff pref]
[    0.434795] pci 0000:01:00.0: BAR 0: assigned [mem 0x1c000000-0x1c01ffff 64bit]
[    0.442194] pci 0000:01:00.0: BAR 6: assigned [mem 0x1c100000-0x1c10ffff pref]
[    0.449457] pci 0000:00:00.0: PCI bridge to [bus 01]
[    0.454493] pci 0000:00:00.0:   bridge window [mem 0x1c000000-0x1c0fffff]
[    0.461351] pci 0000:00:00.0:   bridge window [mem 0x1c100000-0x1c1fffff pref]
[    0.468659] ifx_pcie_bios_map_irq port 0 dev 0000:00:00.0 slot 0 pin 1 
[    0.475314] ifx_pcie_bios_map_irq dev 0000:00:00.0 irq 144 assigned
[    0.481665] ifx_pcie_bios_map_irq port 0 dev 0000:01:00.0 slot 0 pin 1 
[    0.488332] ifx_pcie_bios_map_irq dev 0000:01:00.0 irq 144 assigned
[    0.495619] Switched to clocksource MIPS
[    0.501186] NET: Registered protocol family 2
[    0.506589] TCP established hash table entries: 1024 (order: 0, 4096 bytes)
[    0.513477] TCP bind hash table entries: 1024 (order: 0, 4096 bytes)
[    0.519854] TCP: Hash tables configured (established 1024 bind 1024)
[    0.526421] UDP hash table entries: 256 (order: 0, 4096 bytes)
[    0.532208] UDP-Lite hash table entries: 256 (order: 0, 4096 bytes)
[    0.538906] NET: Registered protocol family 1
[    0.543275] PCI: CLS 0 bytes, default 32
[    0.543692] gptu: totally 6 16-bit timers/counters
[    0.548489] gptu: misc_register on minor 63
[    0.552625] gptu: succeeded to request irq 126
[    0.557117] gptu: succeeded to request irq 127
[    0.561631] gptu: succeeded to request irq 128
[    0.566144] gptu: succeeded to request irq 129
[    0.570658] gptu: succeeded to request irq 130
[    0.575172] gptu: succeeded to request irq 131
[    0.580584] phy-xrx200 gphy-xrx200: requesting lantiq/vr9_phy11g_a2x.bin
[    0.588082] phy-xrx200 gphy-xrx200: booting GPHY0 firmware at 3980000
[    0.594397] phy-xrx200 gphy-xrx200: booting GPHY1 firmware at 3980000
[    0.702193] futex hash table entries: 256 (order: -1, 3072 bytes)
[    0.717287] squashfs: version 4.0 (2009/01/31) Phillip Lougher
[    0.723026] jffs2: version 2.2 (NAND) (SUMMARY) (LZMA) (RTIME) (CMODE_PRIORITY) (c) 2001-2006 Red Hat, Inc.
[    0.734565] io scheduler noop registered
[    0.738406] io scheduler deadline registered (default)
[    0.744308] 1e100c00.serial: ttyLTQ0 at MMIO 0x1e100c00 (irq = 112, base_baud = 0) is a lantiq,asc
[    0.753195] console [ttyLTQ0] enabled
[    0.760526] bootconsole [early0] disabled
[    0.773142] m25p80 spi32766.0: s25fl064k (8192 Kbytes)
[    0.776918] 5 ofpart partitions found on MTD device spi32766.0
[    0.782681] Creating 5 MTD partitions on "spi32766.0":
[    0.787823] 0x000000000000-0x000000020000 : "u-boot"
[    0.793883] 0x000000020000-0x0000006c0000 : "firmware"
[    0.799677] 0x0000001bc984-0x0000006c0000 : "rootfs"
[    0.804264] mtd: device 2 (rootfs) set to be root filesystem
[    0.809817] 1 squashfs-split partitions found on MTD device rootfs
[    0.815061] 0x000000550000-0x0000006c0000 : "rootfs_data"
[    1.530400] 0x0000006c0000-0x0000007c0000 : "dsl_fw"
[    1.534997] 0x0000007c0000-0x0000007d0000 : "config"
[    1.540095] 0x0000007d0000-0x000000800000 : "boardconfig"
[    1.648045] libphy: lantiq,xrx200-mdio: probed
[    1.724353] eth0: attached PHY [Lantiq XWAY PEF7071] (phy_addr=0:00, irq=-1)
[    1.792324] eth0: attached PHY [Lantiq XWAY PEF7071] (phy_addr=0:05, irq=-1)
[    1.860309] eth0: attached PHY [Lantiq XWAY VR9 GPHY 11G v1.4] (phy_addr=0:11, irq=-1)
[    1.928311] eth0: attached PHY [Lantiq XWAY VR9 GPHY 11G v1.4] (phy_addr=0:13, irq=-1)
[    1.936188] wdt 1f8803f0.watchdog: Init done
[    1.941004] NET: Registered protocol family 10
[    1.947467] NET: Registered protocol family 17
[    1.950604] bridge: automatic filtering via arp/ip/ip6tables has been deprecated. Update your scripts to load br_netfilter if you need this.
[    1.963133] 8021q: 802.1Q VLAN Support v1.8
[    2.037186] ath9k,eeprom ath9k_eep: using led pin 0.
[    2.040733] ath9k,eeprom ath9k_eep: loaded ath9k eeprom
[    2.050217] UBIFS error (pid: 1): cannot open "ubi0:rootfs", error -19
[    2.128168] VFS: Mounted root (squashfs filesystem) readonly on device 31:2.
[    2.134629] Freeing unused kernel memory: 188K (804d1000 - 80500000)
[    6.660346] eth0: port 4 got link
[   15.643525] init: Console is alive
[   15.645847] init: - watchdog -
[   29.386130] dwc2 1e101000.ifxhcd: requested GPIO 489
[   30.247817] dwc2 1e101000.ifxhcd: DWC OTG Controller
[   30.251387] dwc2 1e101000.ifxhcd: new USB bus registered, assigned bus number 1
[   30.258725] dwc2 1e101000.ifxhcd: irq 62, io mem 0x00000000
[   30.264250] dwc2 1e101000.ifxhcd: Hardware does not support descriptor DMA mode -
[   30.271692] dwc2 1e101000.ifxhcd: falling back to buffer DMA mode.
[   30.279073] hub 1-0:1.0: USB hub found
[   30.282132] hub 1-0:1.0: 1 port detected
[   31.143819] dwc2 1e106000.ifxhcd: DWC OTG Controller
[   31.147394] dwc2 1e106000.ifxhcd: new USB bus registered, assigned bus number 2
[   31.154732] dwc2 1e106000.ifxhcd: irq 91, io mem 0x00000000
[   31.160256] dwc2 1e106000.ifxhcd: Hardware does not support descriptor DMA mode -
[   31.167700] dwc2 1e106000.ifxhcd: falling back to buffer DMA mode.
[   31.175040] hub 2-0:1.0: USB hub found
[   31.178103] hub 2-0:1.0: 1 port detected
[   31.278364] init: - preinit -
[   33.055682] random: nonblocking pool is initialized
[   37.739476] jffs2: notice: (302) jffs2_build_xattr_subsystem: complete building xattr subsystem, 0 of xdatum (0 unchecked, 0 orphan) and 0 of xref (0 dead, 0 orphan) found.
[   37.791260] mount_root: switching to jffs2 overlay
[   37.885185] procd: - early -
[   37.886763] procd: - watchdog -
[   38.411737] eth0: port 4 lost link
[   38.710446] procd: - ubus -
[   39.734926] procd: - init -
[   41.178312] IFXOS, Version 1.5.19 (c) Copyright 2009, Lantiq Deutschland GmbH
[   41.188347] NET: Registered protocol family 8
[   41.191255] NET: Registered protocol family 20
[   41.200629] tun: Universal TUN/TAP device driver, 1.6
[   41.204284] tun: (C) 1999-2004 Max Krasnyansky <maxk@qualcomm.com>
[   41.226117] PPP generic driver version 2.4.2
[   41.245479] ip6_tables: (C) 2000-2006 Netfilter Core Team
[   41.288421] Lantiq (VRX) DSL CPE MEI driver, version 1.4.8.5, (c) 2013 Lantiq Deutschland GmbH
[   41.288421] 
[   41.288421] Lantiq CPE API Driver version: DSL CPE API V4.16.6.3
[   41.346539] 
[   41.346539] Predefined debug level: 3
[   41.386186] u32 classifier
[   41.387441]     input device check on
[   41.391135]     Actions configured
[   41.406670] Mirror/redirect action on
[   41.421971] nf_conntrack version 0.5.0 (933 buckets, 3732 max)
[   41.464266] Loading modules backported from Linux version master-2015-10-26-0-g28c26cb
[   41.470797] Backport generated by backports.git backports-20150903-4-g12cf266
[   41.482725] ip_tables: (C) 2000-2006 Netfilter Core Team
[   41.495430] Infineon Technologies DEU driver version 2.0.0 
[   41.501291] IFX DEU DES initialized (multiblock).
[   41.505498] IFX DEU AES initialized (multiblock).
[   41.509666] IFX DEU ARC4 initialized (multiblock).
[   41.514299] IFX DEU SHA1 initialized.
[   41.517891] IFX DEU MD5 initialized.
[   41.521499] IFX DEU SHA1_HMAC initialized.
[   41.525618] IFX DEU MD5_HMAC initialized.
[   41.549864] NET: Registered protocol family 24
[   41.597770] xt_time: kernel timezone is -0000
[   41.920742] PCI: Enabling device 0000:00:00.0 (0000 -> 0002)
[   41.925080] PCI: Enabling device 0000:01:00.0 (0000 -> 0002)
[   41.935597] ath: EEPROM regdomain: 0x0
[   41.935670] ath: EEPROM indicates default country code should be used
[   41.935682] ath: doing EEPROM country->regdmn map search
[   41.935704] ath: country maps to regdmn code: 0x3a
[   41.935718] ath: Country alpha2 being used: US
[   41.935729] ath: Regpair used: 0x3a
[   41.945280] ieee80211 phy0: Selected rate control algorithm 'minstrel_ht'
[   41.948696] ieee80211 phy0: Atheros AR9300 Rev:3 mem=0xbc000000, irq=144
[   41.963829] cfg80211: World regulatory domain updated:
[   41.967530] cfg80211:  DFS Master region: unset
[   41.971915] cfg80211:   (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp), (dfs_cac_time)
[   41.981653] cfg80211:   (2402000 KHz - 2472000 KHz @ 40000 KHz), (N/A, 2000 mBm), (N/A)
[   41.989658] cfg80211:   (2457000 KHz - 2482000 KHz @ 40000 KHz), (N/A, 2000 mBm), (N/A)
[   41.997666] cfg80211:   (2474000 KHz - 2494000 KHz @ 20000 KHz), (N/A, 2000 mBm), (N/A)
[   42.005677] cfg80211:   (5170000 KHz - 5250000 KHz @ 80000 KHz, 160000 KHz AUTO), (N/A, 2000 mBm), (N/A)
[   42.015166] cfg80211:   (5250000 KHz - 5330000 KHz @ 80000 KHz, 160000 KHz AUTO), (N/A, 2000 mBm), (0 s)
[   42.024650] cfg80211:   (5490000 KHz - 5730000 KHz @ 160000 KHz), (N/A, 2000 mBm), (0 s)
[   42.032744] cfg80211:   (5735000 KHz - 5835000 KHz @ 80000 KHz), (N/A, 2000 mBm), (N/A)
[   42.040751] cfg80211:   (57240000 KHz - 63720000 KHz @ 2160000 KHz), (N/A, 0 mBm), (N/A)
[   42.108913] cfg80211: Regulatory domain changed to country: US
[   42.113358] cfg80211:  DFS Master region: FCC
[   42.117490] cfg80211:   (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp), (dfs_cac_time)
[   42.127250] cfg80211:   (2402000 KHz - 2472000 KHz @ 40000 KHz), (N/A, 3000 mBm), (N/A)
[   42.135257] cfg80211:   (5170000 KHz - 5250000 KHz @ 80000 KHz, 160000 KHz AUTO), (N/A, 2300 mBm), (N/A)
[   42.144745] cfg80211:   (5250000 KHz - 5330000 KHz @ 80000 KHz, 160000 KHz AUTO), (N/A, 2300 mBm), (0 s)
[   42.154230] cfg80211:   (5490000 KHz - 5730000 KHz @ 160000 KHz), (N/A, 2300 mBm), (0 s)
[   42.162330] cfg80211:   (5735000 KHz - 5835000 KHz @ 80000 KHz), (N/A, 3000 mBm), (N/A)
[   42.170332] cfg80211:   (57240000 KHz - 63720000 KHz @ 2160000 KHz), (N/A, 4000 mBm), (N/A)
[   58.002619] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
[   58.021290] device eth0.1 entered promiscuous mode
[   58.024726] device eth0 entered promiscuous mode
[   58.040949] IPv6: ADDRCONF(NETDEV_UP): br-lan: link is not ready
[   62.562000] eth0: port 4 got link
[   65.513967] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
[   65.521370] br-lan: port 1(eth0.1) entered forwarding state
[   65.525609] br-lan: port 1(eth0.1) entered forwarding state
[   65.532925] IPv6: ADDRCONF(NETDEV_CHANGE): br-lan: link becomes ready
[   66.688209] br-lan: port 1(eth0.1) entered disabled state
[   66.694211] br-lan: port 1(eth0.1) entered forwarding state
[   66.698442] br-lan: port 1(eth0.1) entered forwarding state
[   68.699680] br-lan: port 1(eth0.1) entered forwarding state
[   78.580893] PTM 1.0.27    PTM (E1) firmware version 0.30
[   78.584818] ifxmips_ptm: PTM init succeed
[  211.543691] br-lan: received packet on eth0.1 with own address as source address
[  277.011807] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  277.031797] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  277.059799] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  277.387817] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  277.607891] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  277.699807] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  280.557900] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  284.716569] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  284.731386] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  284.831737] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  284.846527] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  284.891785] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  284.935772] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  285.043798] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  285.415789] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  285.611822] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  290.676027] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  296.059797] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  296.091958] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  296.704061] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  296.763786] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  296.919826] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  296.956021] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  296.975846] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  297.919955] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  297.934761] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  298.020173] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  298.035003] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  298.379798] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  298.551785] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  298.571774] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  298.787775] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  301.783807] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  306.815800] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  312.227797] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  312.259809] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  312.295795] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  312.547829] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  312.695818] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  312.964052] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  313.091775] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  313.547933] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  313.562752] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  313.695928] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  313.710730] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  313.835779] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  313.850604] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  314.395790] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  314.663798] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  318.015780] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  323.055777] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  328.439798] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  328.463794] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  328.639828] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  329.019784] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  329.085444] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  329.207790] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  329.311861] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  329.640023] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  329.654859] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  329.803796] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  330.015792] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  330.311917] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  330.326717] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  331.151780] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  331.215774] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  334.135780] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  339.179862] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  344.459791] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  344.487801] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  344.723822] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  344.945805] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  345.027784] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  345.087828] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  345.347776] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  345.723946] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  345.738773] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  346.087934] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  346.102753] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  346.239777] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  346.431787] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  346.451774] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  346.466605] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  349.975789] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  355.007723] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  360.227800] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  360.251808] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  360.503788] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  360.527268] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  360.807827] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  360.895892] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  361.187774] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  361.807988] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  361.822807] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  361.895975] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  361.910799] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  362.291804] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  362.415795] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  362.823777] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  362.838636] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  365.547779] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  370.591787] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  375.863789] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  375.878627] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  375.915798] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  376.312021] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  376.588021] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  376.743782] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  376.760138] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  377.587949] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  377.602773] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  377.691791] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  377.759939] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  377.774751] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  377.843787] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  377.879787] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  378.559784] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  381.335775] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  386.375715] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  391.675815] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  391.690683] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  392.067819] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  392.199788] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  392.249874] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  392.355839] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  392.535786] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  393.068083] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  393.082892] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  393.123773] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  393.356034] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  393.370857] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  393.471792] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  393.486609] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  394.059786] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  397.299735] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  402.327785] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  407.727789] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  407.755790] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  407.823775] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  408.059794] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  408.139827] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  408.377244] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  408.459855] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  409.139940] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  409.154752] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  409.459883] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  409.474704] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  409.547780] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  409.799746] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  409.831729] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  409.903783] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[  413.435817] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
Fri Dec  4 18:23:27 2015 daemon.err uhttpd[1329]: sh: write error: Broken pipe
Fri Dec  4 18:23:27 2015 daemon.err uhttpd[1329]: sh: write error: Broken pipe
Fri Dec  4 18:23:27 2015 daemon.err uhttpd[1329]: sh: write error: Broken pipe
Fri Dec  4 18:23:27 2015 daemon.err uhttpd[1329]: sh: write error: Broken pipe
Fri Dec  4 18:23:27 2015 daemon.err uhttpd[1329]: sh: write error: Broken pipe
Fri Dec  4 18:23:27 2015 daemon.err uhttpd[1329]: sh: write error: Broken pipe
Fri Dec  4 18:23:27 2015 daemon.err uhttpd[1329]: sh: write error: Broken pipe
Fri Dec  4 18:23:27 2015 daemon.err uhttpd[1329]: sh: write error: Broken pipe
Fri Dec  4 18:23:27 2015 daemon.err uhttpd[1329]: sh: write error: Broken pipe
Fri Dec  4 18:23:27 2015 daemon.err uhttpd[1329]: sh: write error: Broken pipe
Fri Dec  4 18:23:27 2015 daemon.err uhttpd[1329]: sh: write error: Broken pipe
Fri Dec  4 18:23:27 2015 daemon.err uhttpd[1329]: sh: write error: Broken pipe
Fri Dec  4 18:23:28 2015 kern.err kernel: [  418.463807] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
Fri Dec  4 18:23:28 2015 daemon.err uhttpd[1329]: sh: write error: Broken pipe
Fri Dec  4 18:23:28 2015 daemon.err uhttpd[1329]: sh: write error: Broken pipe
Fri Dec  4 18:23:28 2015 daemon.err uhttpd[1329]: sh: write error: Broken pipe
Fri Dec  4 18:23:28 2015 daemon.err uhttpd[1329]: sh: write error: Broken pipe
Fri Dec  4 18:23:28 2015 daemon.err uhttpd[1329]: sh: write error: Broken pipe
Fri Dec  4 18:23:28 2015 daemon.err uhttpd[1329]: sh: write error: Broken pipe
Fri Dec  4 18:23:28 2015 daemon.err uhttpd[1329]: sh: write error: Broken pipe
Fri Dec  4 18:23:28 2015 daemon.err uhttpd[1329]: sh: write error: Broken pipe
Fri Dec  4 18:23:28 2015 daemon.err uhttpd[1329]: sh: write error: Broken pipe
Fri Dec  4 18:23:28 2015 daemon.err uhttpd[1329]: sh: write error: Broken pipe
Fri Dec  4 18:23:28 2015 daemon.err uhttpd[1329]: sh: write error: Broken pipe
Fri Dec  4 18:23:28 2015 daemon.err uhttpd[1329]: sh: write error: Broken pipe
Fri Dec  4 18:23:28 2015 daemon.err uhttpd[1329]: sh: write error: Broken pipe
Fri Dec  4 18:23:28 2015 daemon.err uhttpd[1329]: sh: write error: Broken pipe
Fri Dec  4 18:23:28 2015 daemon.err uhttpd[1329]: sh: write error: Broken pipe
Fri Dec  4 18:23:28 2015 daemon.err uhttpd[1329]: sh: write error: Broken pipe
Fri Dec  4 18:23:28 2015 daemon.err uhttpd[1329]: sh: write error: Broken pipe
Fri Dec  4 18:23:28 2015 daemon.err uhttpd[1329]: sh: write error: Broken pipe
Fri Dec  4 18:23:29 2015 daemon.err uhttpd[1329]: sh: write error: Broken pipe
Fri Dec  4 18:23:29 2015 daemon.err uhttpd[1329]: sh: write error: Broken pipe
Fri Dec  4 18:23:29 2015 daemon.err uhttpd[1329]: sh: write error: Broken pipe
Fri Dec  4 18:23:29 2015 daemon.err uhttpd[1329]: sh: write error: Broken pipe
Fri Dec  4 18:23:29 2015 daemon.err uhttpd[1329]: sh: write error: Broken pipe
Fri Dec  4 18:23:29 2015 daemon.err uhttpd[1329]: sh: write error: Broken pipe
Fri Dec  4 18:23:29 2015 daemon.err uhttpd[1329]: sh: write error: Broken pipe
Fri Dec  4 18:23:29 2015 daemon.err uhttpd[1329]: sh: write error: Broken pipe
Fri Dec  4 18:23:29 2015 daemon.err uhttpd[1329]: sh: write error: Broken pipe
Fri Dec  4 18:23:29 2015 daemon.err uhttpd[1329]: sh: write error: Broken pipe
Fri Dec  4 18:23:29 2015 daemon.err uhttpd[1329]: sh: write error: Broken pipe
Fri Dec  4 18:23:29 2015 daemon.err uhttpd[1329]: sh: write error: Broken pipe
Fri Dec  4 18:23:29 2015 daemon.err uhttpd[1329]: sh: write error: Broken pipe
Fri Dec  4 18:23:29 2015 daemon.err uhttpd[1329]: sh: write error: Broken pipe
Fri Dec  4 18:23:29 2015 daemon.err uhttpd[1329]: sh: write error: Broken pipe
Fri Dec  4 18:23:29 2015 daemon.err uhttpd[1329]: sh: write error: Broken pipe
Fri Dec  4 18:23:29 2015 daemon.err uhttpd[1329]: sh: write error: Broken pipe
Fri Dec  4 18:23:29 2015 daemon.err uhttpd[1329]: sh: write error: Broken pipe
Fri Dec  4 18:23:29 2015 daemon.err uhttpd[1329]: sh: write error: Broken pipe
Fri Dec  4 18:23:29 2015 daemon.err uhttpd[1329]: sh: write error: Broken pipe
Fri Dec  4 18:23:29 2015 daemon.err uhttpd[1329]: sh: write error: Broken pipe
Fri Dec  4 18:23:29 2015 daemon.err uhttpd[1329]: sh: write error: Broken pipe
Fri Dec  4 18:23:29 2015 daemon.err uhttpd[1329]: sh: write error: Broken pipe
Fri Dec  4 18:23:33 2015 daemon.warn pppd[26782]: Timeout waiting for PADO packets
Fri Dec  4 18:23:33 2015 daemon.err pppd[26782]: Unable to complete PPPoE Discovery
Fri Dec  4 18:23:33 2015 daemon.info pppd[26782]: Exit.
Fri Dec  4 18:23:33 2015 daemon.notice netifd: Interface 'wan' is now down
Fri Dec  4 18:23:33 2015 daemon.notice netifd: Interface 'wan' is disabled
Fri Dec  4 18:23:33 2015 kern.err kernel: [  423.827798] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
Fri Dec  4 18:23:33 2015 kern.err kernel: [  423.847825] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
Fri Dec  4 18:23:33 2015 daemon.notice netifd: Interface 'wan' is enabled
Fri Dec  4 18:23:33 2015 daemon.notice netifd: Interface 'wan' is setting up now
Fri Dec  4 18:23:33 2015 kern.err kernel: [  423.875801] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
Fri Dec  4 18:23:33 2015 kern.err kernel: [  423.927791] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
Fri Dec  4 18:23:34 2015 kern.err kernel: [  424.079812] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
Fri Dec  4 18:23:34 2015 daemon.info pppd[28481]: Plugin rp-pppoe.so loaded.
Fri Dec  4 18:23:34 2015 daemon.info pppd[28481]: RP-PPPoE plugin version 3.8p compiled against pppd 2.4.7
Fri Dec  4 18:23:34 2015 daemon.notice pppd[28481]: pppd 2.4.7 started by root, uid 0
Fri Dec  4 18:23:34 2015 kern.err kernel: [  424.457772] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
Fri Dec  4 18:23:34 2015 kern.err kernel: [  424.472604] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
Fri Dec  4 18:23:34 2015 kern.err kernel: [  424.847893] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
Fri Dec  4 18:23:34 2015 kern.err kernel: [  424.862698] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
Fri Dec  4 18:23:34 2015 kern.err kernel: [  424.883747] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
Fri Dec  4 18:23:35 2015 kern.err kernel: [  425.079867] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
Fri Dec  4 18:23:35 2015 kern.err kernel: [  425.094686] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
Fri Dec  4 18:23:35 2015 kern.err kernel: [  425.331740] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
Fri Dec  4 18:23:35 2015 kern.err kernel: [  425.403724] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
Fri Dec  4 18:23:35 2015 kern.err kernel: [  425.491722] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
Fri Dec  4 18:23:35 2015 kern.err kernel: [  426.019759] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
Fri Dec  4 18:23:39 2015 kern.err kernel: [  429.523716] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
Fri Dec  4 18:23:44 2015 kern.err kernel: [  434.539718] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
Fri Dec  4 18:23:49 2015 daemon.warn pppd[28481]: Timeout waiting for PADO packets
Fri Dec  4 18:23:49 2015 daemon.err pppd[28481]: Unable to complete PPPoE Discovery
Fri Dec  4 18:23:49 2015 daemon.info pppd[28481]: Exit.
Fri Dec  4 18:23:49 2015 daemon.notice netifd: Interface 'wan' is now down
Fri Dec  4 18:23:49 2015 daemon.notice netifd: Interface 'wan' is disabled
Fri Dec  4 18:23:49 2015 kern.err kernel: [  439.683797] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
Fri Dec  4 18:23:49 2015 daemon.notice netifd: Interface 'wan' is enabled
Fri Dec  4 18:23:49 2015 daemon.notice netifd: Interface 'wan' is setting up now
Fri Dec  4 18:23:49 2015 kern.err kernel: [  439.707805] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
Fri Dec  4 18:23:49 2015 daemon.info pppd[29387]: Plugin rp-pppoe.so loaded.
Fri Dec  4 18:23:49 2015 daemon.info pppd[29387]: RP-PPPoE plugin version 3.8p compiled against pppd 2.4.7
Fri Dec  4 18:23:49 2015 daemon.notice pppd[29387]: pppd 2.4.7 started by root, uid 0
Fri Dec  4 18:23:49 2015 kern.err kernel: [  439.967431] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
Fri Dec  4 18:23:49 2015 kern.err kernel: [  440.019761] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
Fri Dec  4 18:23:50 2015 kern.err kernel: [  440.159739] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
Fri Dec  4 18:23:50 2015 kern.err kernel: [  440.527730] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
Fri Dec  4 18:23:50 2015 kern.err kernel: [  440.542616] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
Fri Dec  4 18:23:50 2015 kern.err kernel: [  441.019860] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
Fri Dec  4 18:23:50 2015 kern.err kernel: [  441.034664] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
Fri Dec  4 18:23:51 2015 kern.err kernel: [  441.539879] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
Fri Dec  4 18:23:51 2015 kern.err kernel: [  441.554704] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
Fri Dec  4 18:23:51 2015 kern.err kernel: [  441.735726] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
Fri Dec  4 18:23:51 2015 kern.err kernel: [  441.750532] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
Fri Dec  4 18:23:51 2015 kern.err kernel: [  441.775722] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
Fri Dec  4 18:23:52 2015 kern.err kernel: [  442.119791] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
Fri Dec  4 18:23:54 2015 kern.err kernel: [  444.983715] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
Fri Dec  4 18:23:59 2015 kern.err kernel: [  449.999711] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
Fri Dec  4 18:24:04 2015 daemon.warn pppd[29387]: Timeout waiting for PADO packets
Fri Dec  4 18:24:04 2015 daemon.err pppd[29387]: Unable to complete PPPoE Discovery
Fri Dec  4 18:24:04 2015 daemon.info pppd[29387]: Exit.
Fri Dec  4 18:24:05 2015 daemon.notice netifd: Interface 'wan' is now down
Fri Dec  4 18:24:05 2015 daemon.notice netifd: Interface 'wan' is disabled
Fri Dec  4 18:24:05 2015 kern.err kernel: [  455.183805] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
Fri Dec  4 18:24:05 2015 kern.err kernel: [  455.198645] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
Fri Dec  4 18:24:05 2015 daemon.notice netifd: Interface 'wan' is enabled
Fri Dec  4 18:24:05 2015 daemon.notice netifd: Interface 'wan' is setting up now
Fri Dec  4 18:24:05 2015 kern.err kernel: [  455.455812] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
Fri Dec  4 18:24:05 2015 daemon.info pppd[30239]: Plugin rp-pppoe.so loaded.
Fri Dec  4 18:24:05 2015 daemon.info pppd[30239]: RP-PPPoE plugin version 3.8p compiled against pppd 2.4.7
Fri Dec  4 18:24:05 2015 daemon.notice pppd[30239]: pppd 2.4.7 started by root, uid 0
Fri Dec  4 18:24:05 2015 kern.err kernel: [  455.529861] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
Fri Dec  4 18:24:05 2015 kern.err kernel: [  455.544699] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
Fri Dec  4 18:24:06 2015 kern.err kernel: [  456.151723] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
Fri Dec  4 18:24:06 2015 kern.err kernel: [  456.166593] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
Fri Dec  4 18:24:06 2015 kern.err kernel: [  456.455863] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
Fri Dec  4 18:24:06 2015 kern.err kernel: [  456.470664] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
Fri Dec  4 18:24:06 2015 kern.err kernel: [  456.679729] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
Fri Dec  4 18:24:06 2015 kern.err kernel: [  456.763724] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
Fri Dec  4 18:24:07 2015 kern.err kernel: [  457.163883] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
Fri Dec  4 18:24:07 2015 kern.err kernel: [  457.178700] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
Fri Dec  4 18:24:07 2015 kern.err kernel: [  457.327740] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
Fri Dec  4 18:24:07 2015 kern.err kernel: [  457.727789] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime

Attachments (0)

Change History (20)

comment:1 Changed 2 years ago by nbd

Please post the output of /etc/init.d/dsl_control status

comment:2 Changed 2 years ago by d.buffa@…

Hi nbd,

I'll do in the next test session... as I wrote also in the forum I'm having issue also with CC stable build, but in this case DSL connection is ok

The DSL point is remote and I cannot access when i want... I'll do it in the next few days

comment:3 Changed 2 years ago by mkresin

I might have an idea what's going wrong here.

First of all, the ptm_hard_start_xmit: not in showtime error message is annoying but not related to your problem.

Where did you get your dsl firmware from? Do you used the vdsl_fw_install.sh command?

With trunk, you can select a more recent firmware version from menuconfig (Firmware => dsl-vrx200-firmware-xdsl-a). No need to use the vdsl_fw_install.sh command any more.

I might to remember, that I've seen posts in the forums, which claim that recent driver versions are incompatible with the firmware version extracted by vdsl_fw_install.sh.

Last edited 2 years ago by mkresin (previous) (diff)

comment:4 follow-up: Changed 2 years ago by d.buffa@…

With make menuconfig is it auto selected or I have to select it? Because I build from source without select anything about vdsl firmware... then I use the old vdsl_fw_install.sh (if it's not necessary anymore why do not remove this script?)...

I can try with a correct build... can you provide me a snapshot with luci? I'm at work now and here we cannot have access to openwrt git... so when I exit I'll go to test.

Thanks

comment:5 in reply to: ↑ 4 Changed 2 years ago by mkresin

Replying to d.buffa@…:

With make menuconfig is it auto selected or I have to select it?

It's not selected by default, you will have to select it manually and that's why vdsl_fw_install.sh still exists.

Till now, it's even not confirmed that there is an issue with the firmware version extracted by vdsl_fw_install.sh and the new drivers. It's just that some stranger reported something like that, without providing more details.


can you provide me a snapshot with luci?

no

comment:6 Changed 2 years ago by d.buffa@…

Ok, I'll try to compile a version from remote on the dsl site... thank you

D.

comment:7 follow-up: Changed 2 years ago by d.buffa@…

Ok,

today i did a usefull test session. First of all I have to confirm that the DSL sync problem on trunk was the firmware, I have done a new build selecting dsl-vrx200-firmware-xdsl-a firmware and now DSL sync is ok. This is the output of /etc/init.d/dsl_control status command

ATU-C Vendor ID:                          B5,00,42,44,43,4D,A4,15
ATU-C System Vendor ID:                   B5,00,42,44,43,4D,00,00
Chipset:                                  Lantiq-VRX200 Unknown
Firmware Version:                         5.7.4.3.0.6
API Version:                              4.16.6.3
XTSE Capabilities:                        0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x2
Annex:                                    B
Line Mode:                                G.993.2 (VDSL2)
Line State:                               UP [0x801: showtime_tc_sync]
Forward Error Correction Seconds (FECS):  Near:  / Far:
Errored seconds (ES):                     Near: 0 / Far: 14
Severely Errored Seconds (SES):           Near: 0 / Far: 0
Loss of Signal Seconds (LOSS):            Near: 0 / Far: 0
Unavailable Seconds (UAS):                Near: 42 / Far: 42
Header Error Code Errors (HEC):           Near: 0 / Far: 0
Non Pre-emtive CRC errors (CRC_P):        Near: 0 / Far: 0
Pre-emtive CRC errors (CRCP_P):           Near: 0 / Far: 0
Power Management Mode:                    L0 - Synchronized
Latency / Interleave Delay:               Down: Interleave (0.15 ms) / Up: Interleave (7.0 ms)
Data Rate:                                Down: 53.449 Mb/s / Up: 10.799 Mb/s
Line Attenuation (LATN):                  Down: 7.8dB / Up: 8.0dB
Signal Attenuation (SATN):                Down: 7.8dB / Up: 6.4dB
Noise Margin (SNR):                       Down: 25.6dB / Up: 17.1dB
Aggregate Transmit Power(ACTATP):         Down: -23.-2dB / Up: 11.3dB
Max. Attainable Data Rate (ATTNDR):       Down: 114.696 Mb/s / Up: 28.587 Mb/s
Line Uptime Seconds:                      0
Line Uptime:                              down

The log says that DSL line is annex b (and it's strange, I'm in Italy and here annex is a), anyway i tried also with annex b firmare obtaining the same result...

I still have the wan not connected problem, althought system log says that pppoe negotiation is ok

Mon Dec  7 17:27:22 2015 kern.info kernel: [  105.325049] device ptm0 entered promiscuous mode
Mon Dec  7 17:27:22 2015 daemon.notice netifd: Interface 'wan' is enabled
Mon Dec  7 17:27:22 2015 daemon.notice netifd: Interface 'wan' is setting up now
Mon Dec  7 17:27:23 2015 daemon.info pppd[4017]: Plugin rp-pppoe.so loaded.
Mon Dec  7 17:27:23 2015 daemon.info pppd[4017]: RP-PPPoE plugin version 3.8p compiled against pppd 2.4.7
Mon Dec  7 17:27:23 2015 daemon.notice pppd[4017]: pppd 2.4.7 started by root, uid 0
Mon Dec  7 17:27:28 2015 daemon.info pppd[4017]: PPP session is 1537
Mon Dec  7 17:27:28 2015 daemon.warn pppd[4017]: Connected to 88:a2:5e:17:be:be via interface ptm0.835
Mon Dec  7 17:27:28 2015 kern.info kernel: [  110.947277] pppoe-wan: renamed from ppp0
Mon Dec  7 17:27:28 2015 daemon.info pppd[4017]: Using interface pppoe-wan
Mon Dec  7 17:27:28 2015 daemon.notice pppd[4017]: Connect: pppoe-wan <--> ptm0.835
Mon Dec  7 17:27:28 2015 daemon.notice pppd[4017]: PAP authentication succeeded
Mon Dec  7 17:27:28 2015 daemon.notice pppd[4017]: peer from calling number 88:A2:5E:17:BE:BE authorized
Mon Dec  7 17:27:28 2015 daemon.notice netifd: Network device 'pppoe-wan' link is up
Mon Dec  7 17:27:28 2015 daemon.notice netifd: Interface 'wan6' has link connectivity 
Mon Dec  7 17:27:28 2015 daemon.notice pppd[4017]: local  IP address 95.238.236.181
Mon Dec  7 17:27:28 2015 daemon.notice pppd[4017]: remote IP address 192.168.100.1
Mon Dec  7 17:27:28 2015 daemon.notice pppd[4017]: primary   DNS address 85.37.17.17
Mon Dec  7 17:27:28 2015 daemon.notice pppd[4017]: secondary DNS address 85.38.28.72
Mon Dec  7 17:27:28 2015 daemon.notice pppd[4017]: local  LL address fe80::2836:e976:182d:2f96
Mon Dec  7 17:27:28 2015 daemon.notice pppd[4017]: remote LL address fe80::02a0:a50f:fc86:8004
Mon Dec  7 17:27:28 2015 daemon.info pppd[4017]: IPV6CP terminated by peer
Mon Dec  7 17:27:29 2015 daemon.notice netifd: Interface 'wan6' is enabled
Mon Dec  7 17:27:29 2015 daemon.notice netifd: Interface 'wan6' is setting up now
Mon Dec  7 17:27:29 2015 daemon.notice netifd: Interface 'wan' is now up
Mon Dec  7 17:27:29 2015 daemon.info dnsmasq[1376]: reading /tmp/resolv.conf.auto
Mon Dec  7 17:27:29 2015 daemon.info dnsmasq[1376]: using local addresses only for domain lan
Mon Dec  7 17:27:29 2015 daemon.info dnsmasq[1376]: using nameserver 85.37.17.17#53
Mon Dec  7 17:27:29 2015 daemon.info dnsmasq[1376]: using nameserver 85.38.28.72#53
Mon Dec  7 17:27:29 2015 daemon.notice netifd: Network alias 'pppoe-wan' link is up
Mon Dec  7 17:27:29 2015 daemon.notice netifd: Interface 'wan_6' is enabled
Mon Dec  7 17:27:29 2015 daemon.notice netifd: Interface 'wan_6' has link connectivity 
Mon Dec  7 17:27:29 2015 daemon.notice netifd: Interface 'wan_6' is setting up now
Mon Dec  7 17:27:29 2015 daemon.info dnsmasq[1376]: reading /tmp/resolv.conf.auto
Mon Dec  7 17:27:29 2015 daemon.info dnsmasq[1376]: using local addresses only for domain lan
Mon Dec  7 17:27:29 2015 daemon.info dnsmasq[1376]: using nameserver 85.37.17.17#53
Mon Dec  7 17:27:29 2015 daemon.info dnsmasq[1376]: using nameserver 85.38.28.72#53
Mon Dec  7 17:27:29 2015 daemon.info dnsmasq[1376]: using nameserver 85.37.17.17#53
Mon Dec  7 17:27:29 2015 daemon.info dnsmasq[1376]: using nameserver 85.38.28.72#53
Mon Dec  7 17:27:29 2015 daemon.notice netifd: Interface 'wan' has lost the connection
Mon Dec  7 17:27:29 2015 daemon.warn dnsmasq[1376]: no servers found in /tmp/resolv.conf.auto, will retry
Mon Dec  7 17:27:29 2015 daemon.notice netifd: Interface 'wan_6' is now down
Mon Dec  7 17:27:29 2015 daemon.notice netifd: Interface 'wan_6' is disabled
Mon Dec  7 17:27:29 2015 daemon.notice netifd: Interface 'wan_6' has link connectivity loss
Mon Dec  7 17:27:30 2015 user.notice firewall: Reloading firewall due to ifup of wan (pppoe-wan)

There is also the strange log "Interface 'wan' has lost the connection" after pppoe successfully negotiation...

This is interface situation

br-lan    Link encap:Ethernet  HWaddr 30:B5:C2:92:DA:32
          inet addr:172.20.0.1  Bcast:172.20.0.255  Mask:255.255.255.0
          inet6 addr: fe80::32b5:c2ff:fe92:da32%1998147896/64 Scope:Link
          inet6 addr: fd09:c3f8:a1ec::1%1998147896/60 Scope:Global
          UP BROADCAST RUNNING MULTICAST  MTU:1500  Metric:1
          RX packets:4137 errors:0 dropped:0 overruns:0 frame:0
          TX packets:2893 errors:0 dropped:0 overruns:0 carrier:0
          collisions:0 txqueuelen:0
          RX bytes:305090 (297.9 KiB)  TX bytes:676987 (661.1 KiB)

eth0      Link encap:Ethernet  HWaddr 30:B5:C2:92:DA:32
          inet6 addr: fe80::32b5:c2ff:fe92:da32%1997481144/64 Scope:Link
          UP BROADCAST RUNNING MULTICAST  MTU:1500  Metric:1
          RX packets:4628 errors:0 dropped:12 overruns:0 frame:0
          TX packets:2902 errors:0 dropped:0 overruns:0 carrier:0
          collisions:0 txqueuelen:1000
          RX bytes:463394 (452.5 KiB)  TX bytes:703327 (686.8 KiB)

eth0.1    Link encap:Ethernet  HWaddr 30:B5:C2:92:DA:32
          UP BROADCAST RUNNING MULTICAST  MTU:1500  Metric:1
          RX packets:4377 errors:0 dropped:0 overruns:0 frame:0
          TX packets:2889 errors:0 dropped:0 overruns:0 carrier:0
          collisions:0 txqueuelen:0
          RX bytes:325000 (317.3 KiB)  TX bytes:676255 (660.4 KiB)

lo        Link encap:Local Loopback
          inet addr:127.0.0.1  Mask:255.0.0.0
          inet6 addr: ::1%4566360/128 Scope:Host
          UP LOOPBACK RUNNING  MTU:65536  Metric:1
          RX packets:276 errors:0 dropped:0 overruns:0 frame:0
          TX packets:276 errors:0 dropped:0 overruns:0 carrier:0
          collisions:0 txqueuelen:0
          RX bytes:18700 (18.2 KiB)  TX bytes:18700 (18.2 KiB)

pppoe-wan Link encap:Point-to-Point Protocol
          inet addr:79.27.218.2  P-t-P:192.168.100.1  Mask:255.255.255.255
          UP POINTOPOINT RUNNING NOARP MULTICAST  MTU:1492  Metric:1
          RX packets:33 errors:0 dropped:0 overruns:0 frame:0
          TX packets:11 errors:0 dropped:2 overruns:0 carrier:0
          collisions:0 txqueuelen:3
          RX bytes:1697 (1.6 KiB)  TX bytes:490 (490.0 B)

ptm0      Link encap:Ethernet  HWaddr 00:20:DA:86:23:75
          inet6 addr: fe80::220:daff:fe86:2375%1997482008/64 Scope:Link
          UP BROADCAST RUNNING MULTICAST  MTU:1500  Metric:1
          RX packets:451 errors:0 dropped:0 overruns:0 frame:0
          TX packets:453 errors:0 dropped:66 overruns:0 carrier:0
          collisions:0 txqueuelen:1000
          RX bytes:28085 (27.4 KiB)  TX bytes:29657 (28.9 KiB)

ptm0.835  Link encap:Ethernet  HWaddr 30:B5:C2:92:DA:33
          inet6 addr: fe80::32b5:c2ff:fe92:da33%1998147608/64 Scope:Link
          UP BROADCAST RUNNING MULTICAST  MTU:1500  Metric:1
          RX packets:199 errors:0 dropped:0 overruns:0 frame:0
          TX packets:184 errors:0 dropped:0 overruns:0 carrier:0
          collisions:0 txqueuelen:0
          RX bytes:9059 (8.8 KiB)  TX bytes:6490 (6.3 KiB)

This is the routing table

Kernel IP routing table
Destination     Gateway         Genmask         Flags Metric Ref    Use Iface
172.20.0.0      *               255.255.255.0   U     0      0        0 br-lan
192.168.100.1   *               255.255.255.255 UH    0      0        0 pppoe-wan

and obviously in this situation I cannot contact any hosts of the wan side

root@OpenWrt:~# ping 8.8.8.8
PING 8.8.8.8 (8.8.8.8): 56 data bytes
ping: sendto: Network unreachable

but if I force the use of pppoe-wan interface I can ping successfully

root@OpenWrt:~# ping -I pppoe-wan 8.8.8.8
PING 8.8.8.8 (8.8.8.8): 56 data bytes
64 bytes from 8.8.8.8: seq=0 ttl=57 time=25.602 ms
64 bytes from 8.8.8.8: seq=1 ttl=57 time=25.614 ms
64 bytes from 8.8.8.8: seq=2 ttl=57 time=25.442 ms
64 bytes from 8.8.8.8: seq=3 ttl=57 time=25.512 ms
^C
--- 8.8.8.8 ping statistics ---
4 packets transmitted, 4 packets received, 0% packet loss
round-trip min/avg/max = 25.442/25.542/25.614 ms
root@OpenWrt:~#

So DSL is ok, I have ISP connection on the wan side of the router but there is something wrong downstream pppoe negotiation...

This is the complete kernel log

[    0.000000] Linux version 4.1.13 (daniele@danfedvm.localdomain) (gcc version 5.2.0 (OpenWrt GCC 5.2.0 r47680) ) #3 Mon Dec 7 18:17:45 CET 2015
[    0.000000] SoC: VR9 rev 1.2
[    0.000000] bootconsole [early0] enabled
[    0.000000] CPU0 revision is: 00019556 (MIPS 34Kc)
[    0.000000] MIPS: machine is TDW8970 - TP-LINK TD-W8970
[    0.000000] Determined physical RAM map:
[    0.000000]  memory: 04000000 @ 00000000 (usable)
[    0.000000] Initrd not found or empty - disabling initrd
[    0.000000] Zone ranges:
[    0.000000]   Normal   [mem 0x0000000000000000-0x0000000003ffffff]
[    0.000000] Movable zone start for each node
[    0.000000] Early memory node ranges
[    0.000000]   node   0: [mem 0x0000000000000000-0x0000000003ffffff]
[    0.000000] Initmem setup node 0 [mem 0x0000000000000000-0x0000000003ffffff]
[    0.000000] On node 0 totalpages: 16384
[    0.000000] free_area_init_node: node 0, pgdat 804b1e00, node_mem_map 810078c0
[    0.000000]   Normal zone: 128 pages used for memmap
[    0.000000]   Normal zone: 0 pages reserved
[    0.000000]   Normal zone: 16384 pages, LIFO batch:3
[    0.000000] Primary instruction cache 32kB, VIPT, 4-way, linesize 32 bytes.
[    0.000000] Primary data cache 32kB, 4-way, VIPT, cache aliases, linesize 32 bytes
[    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: 16256
[    0.000000] Kernel command line: console=ttyLTQ0,115200 init=/etc/preinit
[    0.000000] PID hash table entries: 256 (order: -2, 1024 bytes)
[    0.000000] Dentry cache hash table entries: 8192 (order: 3, 32768 bytes)
[    0.000000] Inode-cache hash table entries: 4096 (order: 2, 16384 bytes)
[    0.000000] Writing ErrCtl register=00060a20
[    0.000000] Readback ErrCtl register=00060a20
[    0.000000] Memory: 59556K/65536K available (3771K kernel code, 150K rwdata, 988K rodata, 188K init, 207K bss, 5980K reserved, 0K cma-reserved)
[    0.000000] NR_IRQS:256
[    0.000000] CPU Clock: 500MHz
[    0.000000] clocksource MIPS: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041786 ns
[    0.000010] sched_clock: 32 bits at 250MHz, resolution 4ns, wraps every 8589934590ns
[    0.008195] Calibrating delay loop... 332.54 BogoMIPS (lpj=665088)
[    0.042319] pid_max: default: 32768 minimum: 301
[    0.047315] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes)
[    0.053794] Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes)
[    0.066680] clocksource jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns
[    0.076396] pinctrl core: initialized pinctrl subsystem
[    0.082215] NET: Registered protocol family 16
[    0.091078] pinctrl-xway 1e100b10.pinmux: Init done
[    0.096596] dma-xway 1e104100.dma: Init done - hw rev: 7, ports: 7, channels: 28
[    0.207053] dcdc-xrx200 1f106a00.dcdc: Core Voltage : 1016 mV
[    0.324900] ath9k,eeprom ath9k_eep: failed to load eeprom address
[    0.352964] usbcore: registered new interface driver usbfs
[    0.358451] usbcore: registered new interface driver hub
[    0.363820] usbcore: registered new device driver usb
[    0.369152] PCI host bridge to bus 0000:00
[    0.373139] pci_bus 0000:00: root bus resource [mem 0x1c000000-0x1cffffff]
[    0.380056] pci_bus 0000:00: root bus resource [io  0x1d800000-0x1d8fffff]
[    0.386999] pci_bus 0000:00: root bus resource [??? 0x00000000 flags 0x0]
[    0.393854] pci_bus 0000:00: No busn resource found for root bus, will use [bus 00-ff]
[    0.401879] pci 0000:00:00.0: [1bef:0011] type 01 class 0x060000
[    0.401903] ifx_pcie_rc_class_early_fixup: fixed pcie host bridge to pci-pci bridge
[    0.419640] pci 0000:00:00.0: PME# supported from D0 D3hot
[    0.420157] pci 0000:01:00.0: [168c:abcd] type 00 class 0x020000
[    0.420227] pci 0000:01:00.0: reg 0x10: [mem 0x00000000-0x0001ffff 64bit]
[    0.420298] pci 0000:01:00.0: reg 0x30: [mem 0x00000000-0x0000ffff pref]
[    0.420389] pci 0000:01:00.0: supports D1
[    0.420410] pci 0000:01:00.0: PME# supported from D0 D1 D3hot
[    0.420668] pci_bus 0000:01: busn_res: [bus 01-ff] end is updated to 01
[    0.420704] pci_bus 0000:00: busn_res: [bus 00-ff] end is updated to 01
[    0.420763] pci 0000:00:00.0: BAR 8: assigned [mem 0x1c000000-0x1c0fffff]
[    0.427439] pci 0000:00:00.0: BAR 9: assigned [mem 0x1c100000-0x1c1fffff pref]
[    0.434708] pci 0000:01:00.0: BAR 0: assigned [mem 0x1c000000-0x1c01ffff 64bit]
[    0.442107] pci 0000:01:00.0: BAR 6: assigned [mem 0x1c100000-0x1c10ffff pref]
[    0.449370] pci 0000:00:00.0: PCI bridge to [bus 01]
[    0.454406] pci 0000:00:00.0:   bridge window [mem 0x1c000000-0x1c0fffff]
[    0.461264] pci 0000:00:00.0:   bridge window [mem 0x1c100000-0x1c1fffff pref]
[    0.468572] ifx_pcie_bios_map_irq port 0 dev 0000:00:00.0 slot 0 pin 1 
[    0.475227] ifx_pcie_bios_map_irq dev 0000:00:00.0 irq 144 assigned
[    0.481578] ifx_pcie_bios_map_irq port 0 dev 0000:01:00.0 slot 0 pin 1 
[    0.488245] ifx_pcie_bios_map_irq dev 0000:01:00.0 irq 144 assigned
[    0.495537] Switched to clocksource MIPS
[    0.501117] NET: Registered protocol family 2
[    0.506521] TCP established hash table entries: 1024 (order: 0, 4096 bytes)
[    0.513408] TCP bind hash table entries: 1024 (order: 0, 4096 bytes)
[    0.519785] TCP: Hash tables configured (established 1024 bind 1024)
[    0.526350] UDP hash table entries: 256 (order: 0, 4096 bytes)
[    0.532141] UDP-Lite hash table entries: 256 (order: 0, 4096 bytes)
[    0.538831] NET: Registered protocol family 1
[    0.543207] PCI: CLS 0 bytes, default 32
[    0.543624] gptu: totally 6 16-bit timers/counters
[    0.548421] gptu: misc_register on minor 63
[    0.552555] gptu: succeeded to request irq 126
[    0.557048] gptu: succeeded to request irq 127
[    0.561581] gptu: succeeded to request irq 128
[    0.566078] gptu: succeeded to request irq 129
[    0.570588] gptu: succeeded to request irq 130
[    0.575103] gptu: succeeded to request irq 131
[    0.580489] phy-xrx200 gphy-xrx200: requesting lantiq/vr9_phy11g_a2x.bin
[    0.587984] phy-xrx200 gphy-xrx200: booting GPHY0 firmware at 3A00000
[    0.594302] phy-xrx200 gphy-xrx200: booting GPHY1 firmware at 3A00000
[    0.702104] futex hash table entries: 256 (order: -1, 3072 bytes)
[    0.717174] squashfs: version 4.0 (2009/01/31) Phillip Lougher
[    0.722913] jffs2: version 2.2 (NAND) (SUMMARY) (LZMA) (RTIME) (CMODE_PRIORITY) (c) 2001-2006 Red Hat, Inc.
[    0.734477] io scheduler noop registered
[    0.738320] io scheduler deadline registered (default)
[    0.744212] 1e100c00.serial: ttyLTQ0 at MMIO 0x1e100c00 (irq = 112, base_baud = 0) is a lantiq,asc
[    0.753099] console [ttyLTQ0] enabled
[    0.760431] bootconsole [early0] disabled
[    0.971555] spi-xway 1e100800.spi: SPI wait ready timed out stat: a4002003
[    0.977005] m25p80 spi32766.3: wait failed
[    1.179545] spi-xway 1e100800.spi: SPI wait ready timed out stat: a4002003
[    1.185083] m25p80 spi32766.3: s25fl064k (8192 Kbytes)
[    1.190168] 4 ofpart partitions found on MTD device spi32766.3
[    1.195957] Creating 4 MTD partitions on "spi32766.3":
[    1.201097] 0x000000000000-0x000000020000 : "u-boot"
[    1.207166] 0x000000020000-0x0000007c0000 : "firmware"
[    1.213876] 0x0000001bc99c-0x0000007c0000 : "rootfs"
[    1.218512] mtd: device 2 (rootfs) set to be root filesystem
[    1.224032] 1 squashfs-split partitions found on MTD device rootfs
[    1.229284] 0x0000005c0000-0x0000007c0000 : "rootfs_data"
[    1.305930] 0x0000007c0000-0x0000007d0000 : "config"
[    1.310575] 0x0000007d0000-0x000000800000 : "boardconfig"
[    1.316076] Lantiq SoC SPI controller rev 8 (TXFS 8, RXFS 8, DMA 1)
[    1.423669] libphy: lantiq,xrx200-mdio: probed
[    1.496269] eth0: attached PHY [Lantiq XWAY PEF7071] (phy_addr=0:00, irq=-1)
[    1.564243] eth0: attached PHY [Lantiq XWAY PEF7071] (phy_addr=0:05, irq=-1)
[    1.632227] eth0: attached PHY [Lantiq XWAY VR9 GPHY 11G v1.4] (phy_addr=0:11, irq=-1)
[    1.700226] eth0: attached PHY [Lantiq XWAY VR9 GPHY 11G v1.4] (phy_addr=0:13, irq=-1)
[    1.708094] wdt 1f8803f0.watchdog: Init done
[    1.712950] NET: Registered protocol family 10
[    1.719420] NET: Registered protocol family 17
[    1.722559] bridge: automatic filtering via arp/ip/ip6tables has been deprecated. Update your scripts to load br_netfilter if you need this.
[    1.735089] 8021q: 802.1Q VLAN Support v1.8
[    1.741573] ath9k,eeprom ath9k_eep: using led pin 0.
[    1.745132] ath9k,eeprom ath9k_eep: loaded ath9k eeprom
[    1.754607] UBIFS error (pid: 1): cannot open "ubi0:rootfs", error -19
[    1.765409] VFS: Mounted root (squashfs filesystem) readonly on device 31:2.
[    1.771918] Freeing unused kernel memory: 188K (804d1000 - 80500000)
[    1.800124] random: nonblocking pool is initialized
[    3.061729] init: Console is alive
[    3.064023] init: - watchdog -
[    4.618784] dwc2 1e101000.ifxhcd: requested GPIO 489
[    5.479731] dwc2 1e101000.ifxhcd: DWC OTG Controller
[    5.483302] dwc2 1e101000.ifxhcd: new USB bus registered, assigned bus number 1
[    5.490639] dwc2 1e101000.ifxhcd: irq 62, io mem 0x00000000
[    5.496166] dwc2 1e101000.ifxhcd: Hardware does not support descriptor DMA mode -
[    5.503608] dwc2 1e101000.ifxhcd: falling back to buffer DMA mode.
[    5.510937] hub 1-0:1.0: USB hub found
[    5.514029] hub 1-0:1.0: 1 port detected
[    5.627791] eth0: port 5 got link
[    6.375738] dwc2 1e106000.ifxhcd: DWC OTG Controller
[    6.379310] dwc2 1e106000.ifxhcd: new USB bus registered, assigned bus number 2
[    6.386647] dwc2 1e106000.ifxhcd: irq 91, io mem 0x00000000
[    6.392172] dwc2 1e106000.ifxhcd: Hardware does not support descriptor DMA mode -
[    6.399616] dwc2 1e106000.ifxhcd: falling back to buffer DMA mode.
[    6.406969] hub 2-0:1.0: USB hub found
[    6.410019] hub 2-0:1.0: 1 port detected
[    7.137808] init: - preinit -
[   10.935214] jffs2: notice: (298) jffs2_build_xattr_subsystem: complete building xattr subsystem, 0 of xdatum (0 unchecked, 0 orphan) and 0 of xref (0 dead, 0 orphan) found.
[   11.147559] spi-xway 1e100800.spi: SPI wait ready timed out stat: c4002003
[   11.153018] m25p80 spi32766.3: wait failed
[   11.355554] spi-xway 1e100800.spi: SPI wait ready timed out stat: c4002003
[   11.559559] spi-xway 1e100800.spi: SPI wait ready timed out stat: c4002003
[   11.565012] m25p80 spi32766.3: wait failed
[   11.767555] spi-xway 1e100800.spi: SPI wait ready timed out stat: c4002003
[   11.774087] mount_root: switching to jffs2 overlay
[   11.979559] spi-xway 1e100800.spi: SPI wait ready timed out stat: c4002003
[   11.985016] m25p80 spi32766.3: wait failed
[   12.187555] spi-xway 1e100800.spi: SPI wait ready timed out stat: c4002003
[   12.399559] spi-xway 1e100800.spi: SPI wait ready timed out stat: c4002003
[   12.405021] m25p80 spi32766.3: wait failed
[   12.607557] spi-xway 1e100800.spi: SPI wait ready timed out stat: c4002003
[   12.643260] procd: - early -
[   12.644919] procd: - watchdog -
[   12.885938] spi-xway 1e100800.spi: SPI wait ready timed out stat: c4002003
[   12.891434] m25p80 spi32766.3: wait failed
[   13.115620] spi-xway 1e100800.spi: SPI wait ready timed out stat: c4002003
[   13.482146] procd: - ubus -
[   13.607559] spi-xway 1e100800.spi: SPI wait ready timed out stat: 44002003
[   13.613025] m25p80 spi32766.3: wait failed
[   13.627585] eth0: port 5 lost link
[   13.815553] spi-xway 1e100800.spi: SPI wait ready timed out stat: 44002003
[   14.019556] spi-xway 1e100800.spi: SPI wait ready timed out stat: c4002003
[   14.025017] m25p80 spi32766.3: wait failed
[   14.227551] spi-xway 1e100800.spi: SPI wait ready timed out stat: c4002003
[   14.431554] spi-xway 1e100800.spi: SPI wait ready timed out stat: c4002003
[   14.437010] m25p80 spi32766.3: wait failed
[   14.639559] spi-xway 1e100800.spi: SPI wait ready timed out stat: c4002003
[   14.656618] procd: - init -
[   14.851559] spi-xway 1e100800.spi: SPI wait ready timed out stat: c4002003
[   14.857017] m25p80 spi32766.3: wait failed
[   15.059551] spi-xway 1e100800.spi: SPI wait ready timed out stat: c4002003
[   15.263580] spi-xway 1e100800.spi: SPI wait ready timed out stat: b4002003
[   15.269048] m25p80 spi32766.3: wait failed
[   15.471552] spi-xway 1e100800.spi: SPI wait ready timed out stat: b4002003
[   15.675555] spi-xway 1e100800.spi: SPI wait ready timed out stat: c4002003
[   15.681013] m25p80 spi32766.3: wait failed
[   15.883551] spi-xway 1e100800.spi: SPI wait ready timed out stat: c4002003
[   16.087557] spi-xway 1e100800.spi: SPI wait ready timed out stat: b4002003
[   16.093018] m25p80 spi32766.3: wait failed
[   16.295551] spi-xway 1e100800.spi: SPI wait ready timed out stat: b4002003
[   16.499555] spi-xway 1e100800.spi: SPI wait ready timed out stat: c4002003
[   16.505011] m25p80 spi32766.3: wait failed
[   16.707551] spi-xway 1e100800.spi: SPI wait ready timed out stat: c4002003
[   16.911555] spi-xway 1e100800.spi: SPI wait ready timed out stat: c4002003
[   16.917005] m25p80 spi32766.3: wait failed
[   17.119550] spi-xway 1e100800.spi: SPI wait ready timed out stat: c4002003
[   17.323557] spi-xway 1e100800.spi: SPI wait ready timed out stat: b4002003
[   17.329009] m25p80 spi32766.3: wait failed
[   17.531551] spi-xway 1e100800.spi: SPI wait ready timed out stat: b4002003
[   17.735556] spi-xway 1e100800.spi: SPI wait ready timed out stat: c4002003
[   17.741015] m25p80 spi32766.3: wait failed
[   17.943554] spi-xway 1e100800.spi: SPI wait ready timed out stat: c4002003
[   18.147556] spi-xway 1e100800.spi: SPI wait ready timed out stat: c4002003
[   18.153008] m25p80 spi32766.3: wait failed
[   18.355551] spi-xway 1e100800.spi: SPI wait ready timed out stat: c4002003
[   18.559555] spi-xway 1e100800.spi: SPI wait ready timed out stat: c4002003
[   18.565021] m25p80 spi32766.3: wait failed
[   18.767589] spi-xway 1e100800.spi: SPI wait ready timed out stat: c4002003
[   18.971572] spi-xway 1e100800.spi: SPI wait ready timed out stat: c4002003
[   18.977027] m25p80 spi32766.3: wait failed
[   19.179551] spi-xway 1e100800.spi: SPI wait ready timed out stat: c4002003
[   19.391559] spi-xway 1e100800.spi: SPI wait ready timed out stat: c4002003
[   19.397022] m25p80 spi32766.3: wait failed
[   19.599552] spi-xway 1e100800.spi: SPI wait ready timed out stat: c4002003
[   19.803556] spi-xway 1e100800.spi: SPI wait ready timed out stat: c4002003
[   19.809015] m25p80 spi32766.3: wait failed
[   20.011554] spi-xway 1e100800.spi: SPI wait ready timed out stat: c4002003
[   20.215556] spi-xway 1e100800.spi: SPI wait ready timed out stat: c4002003
[   20.221010] m25p80 spi32766.3: wait failed
[   20.423551] spi-xway 1e100800.spi: SPI wait ready timed out stat: c4002003
[   20.627580] spi-xway 1e100800.spi: SPI wait ready timed out stat: c4002003
[   20.633043] m25p80 spi32766.3: wait failed
[   20.835551] spi-xway 1e100800.spi: SPI wait ready timed out stat: c4002003
[   21.039555] spi-xway 1e100800.spi: SPI wait ready timed out stat: c4002003
[   21.045006] m25p80 spi32766.3: wait failed
[   21.247551] spi-xway 1e100800.spi: SPI wait ready timed out stat: c4002003
[   21.487559] spi-xway 1e100800.spi: SPI wait ready timed out stat: c4002003
[   21.493015] m25p80 spi32766.3: wait failed
[   21.695552] spi-xway 1e100800.spi: SPI wait ready timed out stat: c4002003
[   21.899557] spi-xway 1e100800.spi: SPI wait ready timed out stat: c4002003
[   21.905016] m25p80 spi32766.3: wait failed
[   22.107553] spi-xway 1e100800.spi: SPI wait ready timed out stat: c4002003
[   22.311555] spi-xway 1e100800.spi: SPI wait ready timed out stat: b4002003
[   22.317015] m25p80 spi32766.3: wait failed
[   22.519551] spi-xway 1e100800.spi: SPI wait ready timed out stat: b4002003
[   22.731559] spi-xway 1e100800.spi: SPI wait ready timed out stat: c4002003
[   22.737016] m25p80 spi32766.3: wait failed
[   22.939551] spi-xway 1e100800.spi: SPI wait ready timed out stat: c4002003
[   23.199559] spi-xway 1e100800.spi: SPI wait ready timed out stat: c4002003
[   23.205024] m25p80 spi32766.3: wait failed
[   23.407551] spi-xway 1e100800.spi: SPI wait ready timed out stat: c4002003
[   23.611557] spi-xway 1e100800.spi: SPI wait ready timed out stat: c4002003
[   23.617014] m25p80 spi32766.3: wait failed
[   23.819552] spi-xway 1e100800.spi: SPI wait ready timed out stat: c4002003
[   24.023565] spi-xway 1e100800.spi: SPI wait ready timed out stat: 44002003
[   24.029020] m25p80 spi32766.3: wait failed
[   24.231551] spi-xway 1e100800.spi: SPI wait ready timed out stat: 44002003
[   24.435556] spi-xway 1e100800.spi: SPI wait ready timed out stat: c4002003
[   24.441020] m25p80 spi32766.3: wait failed
[   24.643551] spi-xway 1e100800.spi: SPI wait ready timed out stat: c4002003
[   24.847558] spi-xway 1e100800.spi: SPI wait ready timed out stat: c4002003
[   24.853017] m25p80 spi32766.3: wait failed
[   25.055551] spi-xway 1e100800.spi: SPI wait ready timed out stat: c4002003
[   25.318994] spi-xway 1e100800.spi: SPI wait ready timed out stat: c4002003
[   25.324489] m25p80 spi32766.3: wait failed
[   25.534717] spi-xway 1e100800.spi: SPI wait ready timed out stat: c4002003
[   25.795559] spi-xway 1e100800.spi: SPI wait ready timed out stat: c4002003
[   25.801018] m25p80 spi32766.3: wait failed
[   26.003553] spi-xway 1e100800.spi: SPI wait ready timed out stat: c4002003
[   26.235559] spi-xway 1e100800.spi: SPI wait ready timed out stat: 44002003
[   26.241028] m25p80 spi32766.3: wait failed
[   26.443551] spi-xway 1e100800.spi: SPI wait ready timed out stat: 44002003
[   26.647556] spi-xway 1e100800.spi: SPI wait ready timed out stat: c4002003
[   26.653013] m25p80 spi32766.3: wait failed
[   26.855551] spi-xway 1e100800.spi: SPI wait ready timed out stat: c4002003
[   27.059556] spi-xway 1e100800.spi: SPI wait ready timed out stat: c4002003
[   27.065007] m25p80 spi32766.3: wait failed
[   27.267576] spi-xway 1e100800.spi: SPI wait ready timed out stat: c4002003
[   27.486881] IFXOS, Version 1.5.19 (c) Copyright 2009, Lantiq Deutschland GmbH
[   27.496923] NET: Registered protocol family 8
[   27.499883] NET: Registered protocol family 20
[   27.509107] tun: Universal TUN/TAP device driver, 1.6
[   27.512764] tun: (C) 1999-2004 Max Krasnyansky <maxk@qualcomm.com>
[   27.537698] PPP generic driver version 2.4.2
[   27.557381] ip6_tables: (C) 2000-2006 Netfilter Core Team
[   27.587653] spi-xway 1e100800.spi: SPI wait ready timed out stat: c4002003
[   27.593123] m25p80 spi32766.3: wait failed
[   27.613042] Lantiq (VRX) DSL CPE MEI driver, version 1.4.8.5, (c) 2013 Lantiq Deutschland GmbH
[   27.613042] 
[   27.613042] Lantiq CPE API Driver version: DSL CPE API V4.16.6.3
[   27.667633] 
[   27.667633] Predefined debug level: 3
[   27.709734] u32 classifier
[   27.710986]     input device check on
[   27.714679]     Actions configured
[   27.749741] Mirror/redirect action on
[   27.764487] nf_conntrack version 0.5.0 (933 buckets, 3732 max)
[   27.775023] Loading modules backported from Linux version master-2015-12-03-0-g1677f62
[   27.781552] Backport generated by backports.git backports-20151120-0-g906a6b3
[   27.792409] ip_tables: (C) 2000-2006 Netfilter Core Team
[   27.796706] spi-xway 1e100800.spi: SPI wait ready timed out stat: c4002003
[   27.811703] Infineon Technologies DEU driver version 2.0.0 
[   27.817563] IFX DEU DES initialized (multiblock).
[   27.821796] IFX DEU AES initialized (multiblock).
[   27.825949] IFX DEU ARC4 initialized (multiblock).
[   27.830579] IFX DEU SHA1 initialized.
[   27.834186] IFX DEU MD5 initialized.
[   27.837777] IFX DEU SHA1_HMAC initialized.
[   27.841896] IFX DEU MD5_HMAC initialized.
[   27.868228] NET: Registered protocol family 24
[   27.922929] xt_time: kernel timezone is -0000
[   28.071583] spi-xway 1e100800.spi: SPI wait ready timed out stat: c4002003
[   28.077057] m25p80 spi32766.3: wait failed
[   28.279552] spi-xway 1e100800.spi: SPI wait ready timed out stat: c4002003
[   28.316751] cfg80211: World regulatory domain updated:
[   28.320505] cfg80211:  DFS Master region: unset
[   28.324814] cfg80211:   (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp), (dfs_cac_time)
[   28.334576] cfg80211:   (2402000 KHz - 2472000 KHz @ 40000 KHz), (N/A, 2000 mBm), (N/A)
[   28.342580] cfg80211:   (2457000 KHz - 2482000 KHz @ 40000 KHz), (N/A, 2000 mBm), (N/A)
[   28.350587] cfg80211:   (2474000 KHz - 2494000 KHz @ 20000 KHz), (N/A, 2000 mBm), (N/A)
[   28.358600] cfg80211:   (5170000 KHz - 5250000 KHz @ 80000 KHz, 160000 KHz AUTO), (N/A, 2000 mBm), (N/A)
[   28.368086] cfg80211:   (5250000 KHz - 5330000 KHz @ 80000 KHz, 160000 KHz AUTO), (N/A, 2000 mBm), (0 s)
[   28.377575] cfg80211:   (5490000 KHz - 5730000 KHz @ 160000 KHz), (N/A, 2000 mBm), (0 s)
[   28.385666] cfg80211:   (5735000 KHz - 5835000 KHz @ 80000 KHz), (N/A, 2000 mBm), (N/A)
[   28.393672] cfg80211:   (57240000 KHz - 63720000 KHz @ 2160000 KHz), (N/A, 0 mBm), (N/A)
[   28.627586] spi-xway 1e100800.spi: SPI wait ready timed out stat: c4002003
[   28.633059] m25p80 spi32766.3: wait failed
[   28.835551] spi-xway 1e100800.spi: SPI wait ready timed out stat: c4002003
[   28.937986] PCI: Enabling device 0000:00:00.0 (0000 -> 0002)
[   28.942339] PCI: Enabling device 0000:01:00.0 (0000 -> 0002)
[   28.952901] ath: EEPROM regdomain: 0x0
[   28.952925] ath: EEPROM indicates default country code should be used
[   28.952936] ath: doing EEPROM country->regdmn map search
[   28.952959] ath: country maps to regdmn code: 0x3a
[   28.952971] ath: Country alpha2 being used: US
[   28.952983] ath: Regpair used: 0x3a
[   28.962568] ieee80211 phy0: Selected rate control algorithm 'minstrel_ht'
[   28.965982] ieee80211 phy0: Atheros AR9300 Rev:3 mem=0xbc000000, irq=144
[   28.974274] cfg80211: Regulatory domain changed to country: US
[   28.978722] cfg80211:  DFS Master region: FCC
[   28.982854] cfg80211:   (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp), (dfs_cac_time)
[   28.992615] cfg80211:   (2402000 KHz - 2472000 KHz @ 40000 KHz), (N/A, 3000 mBm), (N/A)
[   29.000623] cfg80211:   (5170000 KHz - 5250000 KHz @ 80000 KHz, 160000 KHz AUTO), (N/A, 2300 mBm), (N/A)
[   29.010111] cfg80211:   (5250000 KHz - 5330000 KHz @ 80000 KHz, 160000 KHz AUTO), (N/A, 2300 mBm), (0 s)
[   29.019603] cfg80211:   (5490000 KHz - 5730000 KHz @ 160000 KHz), (N/A, 2300 mBm), (0 s)
[   29.027690] cfg80211:   (5735000 KHz - 5835000 KHz @ 80000 KHz), (N/A, 3000 mBm), (N/A)
[   29.035697] cfg80211:   (57240000 KHz - 63720000 KHz @ 2160000 KHz), (N/A, 4000 mBm), (N/A)
[   29.087639] spi-xway 1e100800.spi: SPI wait ready timed out stat: c4002003
[   29.093107] m25p80 spi32766.3: wait failed
[   29.295558] spi-xway 1e100800.spi: SPI wait ready timed out stat: c4002003
[   29.499555] spi-xway 1e100800.spi: SPI wait ready timed out stat: c4002003
[   29.505010] m25p80 spi32766.3: wait failed
[   29.707552] spi-xway 1e100800.spi: SPI wait ready timed out stat: c4002003
[   31.135558] spi-xway 1e100800.spi: SPI wait ready timed out stat: b4002003
[   31.141015] m25p80 spi32766.3: wait failed
[   31.343550] spi-xway 1e100800.spi: SPI wait ready timed out stat: b4002003
[   36.024533] PTM 1.0.27    PTM (E1) firmware version 0.30
[   36.028457] ifxmips_ptm: PTM init succeed
[   41.469686] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
[   41.499113] device eth0.1 entered promiscuous mode
[   41.502520] device eth0 entered promiscuous mode
[   41.532964] IPv6: ADDRCONF(NETDEV_UP): br-lan: link is not ready
[   41.607702] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[   41.646804] device ptm0 entered promiscuous mode
[   41.655724] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[   41.803458] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[   41.907672] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[   42.003791] eth0: port 5 got link
[   42.005961] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
[   42.047687] br-lan: port 1(eth0.1) entered forwarding state
[   42.051925] br-lan: port 1(eth0.1) entered forwarding state
[   42.103638] IPv6: ADDRCONF(NETDEV_CHANGE): br-lan: link becomes ready
[   42.227694] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[   42.463746] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[   42.799839] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[   42.814645] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[   43.004368] br-lan: port 1(eth0.1) entered disabled state
[   43.175659] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[   43.463842] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[   43.478658] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[   43.576624] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[   43.807690] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[   44.004515] br-lan: port 1(eth0.1) entered forwarding state
[   44.008744] br-lan: port 1(eth0.1) entered forwarding state
[   44.159716] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[   44.439725] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[   46.011605] br-lan: port 1(eth0.1) entered forwarding state
[   48.692223] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[   53.723729] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[   58.902353] device ptm0 left promiscuous mode
[   58.919727] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[   58.938000] device ptm0 entered promiscuous mode
[   58.947733] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[   59.135739] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[   59.225649] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[   59.383684] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[   59.563649] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[   59.923667] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[   60.135800] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[   60.150617] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[   60.179640] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[   60.207652] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[   60.383793] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[   60.398613] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[   60.891663] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[   60.906483] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[   64.243640] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[   69.259728] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[   74.412827] device ptm0 left promiscuous mode
[   74.428735] device ptm0 entered promiscuous mode
[   74.432104] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[   74.455734] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[   74.575772] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[   74.740484] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[   74.827652] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[   74.879651] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[   75.111689] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[   75.575808] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[   75.590638] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[   76.011658] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[   76.111801] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[   76.126620] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[   76.519664] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[   76.791642] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[   76.939708] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[   79.759653] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
[   84.706986] enter showtime
[   84.712034] enter showtime
[   89.900296] device ptm0 left promiscuous mode
[   89.916678] device ptm0 entered promiscuous mode
[  105.308385] device ptm0 left promiscuous mode
[  105.325049] device ptm0 entered promiscuous mode
[  110.947277] pppoe-wan: renamed from ppp0

and complete system log

Mon Dec  7 17:26:28 2015 daemon.info dnsmasq[1376]: using local addresses only for domain lan
Mon Dec  7 17:26:28 2015 daemon.warn dnsmasq[1376]: no servers found in /tmp/resolv.conf.auto, will retry
Mon Dec  7 17:26:28 2015 daemon.info dnsmasq[1376]: read /etc/hosts - 4 addresses
Mon Dec  7 17:26:28 2015 daemon.info dnsmasq[1376]: read /tmp/hosts/dhcp - 1 addresses
Mon Dec  7 17:26:28 2015 daemon.info dnsmasq-dhcp[1376]: read /etc/ethers - 0 addresses
Mon Dec  7 17:26:29 2015 daemon.info dnsmasq-dhcp[1376]: DHCPREQUEST(br-lan) 192.168.1.139 88:ae:1d:57:7e:55 
Mon Dec  7 17:26:29 2015 daemon.info dnsmasq-dhcp[1376]: DHCPACK(br-lan) 192.168.1.139 88:ae:1d:57:7e:55 dan10alm
Mon Dec  7 17:26:29 2015 daemon.warn odhcpd[812]: DHCPV6 SOLICIT IA_NA from 000100011d63f4e488ae1d577e55 on br-lan: ok fdd2:2baa:11c0::c2c/128 
Mon Dec  7 17:26:30 2015 daemon.info dnsmasq[1376]: read /etc/hosts - 4 addresses
Mon Dec  7 17:26:30 2015 daemon.info dnsmasq[1376]: read /tmp/hosts/odhcpd - 0 addresses
Mon Dec  7 17:26:30 2015 daemon.info dnsmasq[1376]: read /tmp/hosts/dhcp - 1 addresses
Mon Dec  7 17:26:30 2015 daemon.info dnsmasq-dhcp[1376]: read /etc/ethers - 0 addresses
Mon Dec  7 17:26:30 2015 daemon.warn odhcpd[812]: DHCPV6 REQUEST IA_NA from 000100011d63f4e488ae1d577e55 on br-lan: ok fdd2:2baa:11c0::c2c/128 
Mon Dec  7 17:26:31 2015 kern.err kernel: [   53.723729] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
Mon Dec  7 17:26:34 2015 authpriv.notice dropbear[1372]: Password auth succeeded for 'root' from 192.168.1.139:13811
Mon Dec  7 17:26:36 2015 daemon.warn pppd[1104]: Timeout waiting for PADO packets
Mon Dec  7 17:26:36 2015 daemon.err pppd[1104]: Unable to complete PPPoE Discovery
Mon Dec  7 17:26:36 2015 daemon.info pppd[1104]: Exit.
Mon Dec  7 17:26:36 2015 daemon.notice netifd: Interface 'wan' is now down
Mon Dec  7 17:26:36 2015 kern.info kernel: [   58.902353] device ptm0 left promiscuous mode
Mon Dec  7 17:26:36 2015 daemon.notice netifd: Interface 'wan' is disabled
Mon Dec  7 17:26:36 2015 kern.err kernel: [   58.919727] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
Mon Dec  7 17:26:36 2015 daemon.notice netifd: Interface 'wan' is enabled
Mon Dec  7 17:26:36 2015 daemon.notice netifd: Interface 'wan' is setting up now
Mon Dec  7 17:26:36 2015 kern.info kernel: [   58.938000] device ptm0 entered promiscuous mode
Mon Dec  7 17:26:36 2015 kern.err kernel: [   58.947733] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
Mon Dec  7 17:26:36 2015 kern.err kernel: [   59.135739] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
Mon Dec  7 17:26:36 2015 daemon.info pppd[1611]: Plugin rp-pppoe.so loaded.
Mon Dec  7 17:26:36 2015 daemon.info pppd[1611]: RP-PPPoE plugin version 3.8p compiled against pppd 2.4.7
Mon Dec  7 17:26:36 2015 daemon.notice pppd[1611]: pppd 2.4.7 started by root, uid 0
Mon Dec  7 17:26:36 2015 kern.err kernel: [   59.225649] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
Mon Dec  7 17:26:37 2015 kern.err kernel: [   59.383684] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
Mon Dec  7 17:26:37 2015 kern.err kernel: [   59.563649] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
Mon Dec  7 17:26:37 2015 kern.err kernel: [   59.923667] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
Mon Dec  7 17:26:37 2015 kern.err kernel: [   60.135800] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
Mon Dec  7 17:26:37 2015 kern.err kernel: [   60.150617] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
Mon Dec  7 17:26:37 2015 kern.err kernel: [   60.179640] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
Mon Dec  7 17:26:37 2015 kern.err kernel: [   60.207652] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
Mon Dec  7 17:26:38 2015 kern.err kernel: [   60.383793] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
Mon Dec  7 17:26:38 2015 kern.err kernel: [   60.398613] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
Mon Dec  7 17:26:38 2015 kern.err kernel: [   60.891663] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
Mon Dec  7 17:26:38 2015 kern.err kernel: [   60.906483] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
Mon Dec  7 17:26:41 2015 kern.err kernel: [   64.243640] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
Mon Dec  7 17:26:46 2015 kern.err kernel: [   69.259728] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
Mon Dec  7 17:26:51 2015 daemon.warn pppd[1611]: Timeout waiting for PADO packets
Mon Dec  7 17:26:51 2015 daemon.err pppd[1611]: Unable to complete PPPoE Discovery
Mon Dec  7 17:26:51 2015 daemon.info pppd[1611]: Exit.
Mon Dec  7 17:26:52 2015 daemon.notice netifd: Interface 'wan' is now down
Mon Dec  7 17:26:52 2015 kern.info kernel: [   74.412827] device ptm0 left promiscuous mode
Mon Dec  7 17:26:52 2015 daemon.notice netifd: Interface 'wan' is disabled
Mon Dec  7 17:26:52 2015 kern.info kernel: [   74.428735] device ptm0 entered promiscuous mode
Mon Dec  7 17:26:52 2015 kern.err kernel: [   74.432104] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
Mon Dec  7 17:26:52 2015 daemon.notice netifd: Interface 'wan' is enabled
Mon Dec  7 17:26:52 2015 daemon.notice netifd: Interface 'wan' is setting up now
Mon Dec  7 17:26:52 2015 kern.err kernel: [   74.455734] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
Mon Dec  7 17:26:52 2015 kern.err kernel: [   74.575772] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
Mon Dec  7 17:26:52 2015 daemon.info pppd[2201]: Plugin rp-pppoe.so loaded.
Mon Dec  7 17:26:52 2015 daemon.info pppd[2201]: RP-PPPoE plugin version 3.8p compiled against pppd 2.4.7
Mon Dec  7 17:26:52 2015 daemon.notice pppd[2201]: pppd 2.4.7 started by root, uid 0
Mon Dec  7 17:26:52 2015 kern.err kernel: [   74.740484] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
Mon Dec  7 17:26:52 2015 kern.err kernel: [   74.827652] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
Mon Dec  7 17:26:52 2015 kern.err kernel: [   74.879651] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
Mon Dec  7 17:26:52 2015 kern.err kernel: [   75.111689] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
Mon Dec  7 17:26:53 2015 kern.err kernel: [   75.575808] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
Mon Dec  7 17:26:53 2015 kern.err kernel: [   75.590638] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
Mon Dec  7 17:26:53 2015 kern.err kernel: [   76.011658] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
Mon Dec  7 17:26:53 2015 kern.err kernel: [   76.111801] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
Mon Dec  7 17:26:53 2015 kern.err kernel: [   76.126620] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
Mon Dec  7 17:26:54 2015 kern.err kernel: [   76.519664] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
Mon Dec  7 17:26:54 2015 kern.err kernel: [   76.791642] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
Mon Dec  7 17:26:54 2015 kern.err kernel: [   76.939708] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
Mon Dec  7 17:26:57 2015 kern.err kernel: [   79.759653] /home/daniele/dev/openwrt/openwrt/build_dir/target-mips_34kc+dsp_musl-1.1.11/linux-lantiq_xrx200/ltq-ptm-vr9/ifxmips_ptm_vdsl.c:281:ptm_hard_start_xmit: not in showtime
Mon Dec  7 17:27:02 2015 kern.warn kernel: [   84.706986] enter showtime
Mon Dec  7 17:27:02 2015 kern.warn kernel: [   84.712034] enter showtime
Mon Dec  7 17:27:07 2015 daemon.warn pppd[2201]: Timeout waiting for PADO packets
Mon Dec  7 17:27:07 2015 daemon.err pppd[2201]: Unable to complete PPPoE Discovery
Mon Dec  7 17:27:07 2015 daemon.info pppd[2201]: Exit.
Mon Dec  7 17:27:07 2015 daemon.notice netifd: Interface 'wan' is now down
Mon Dec  7 17:27:07 2015 kern.info kernel: [   89.900296] device ptm0 left promiscuous mode
Mon Dec  7 17:27:07 2015 daemon.notice netifd: Interface 'wan' is disabled
Mon Dec  7 17:27:07 2015 kern.info kernel: [   89.916678] device ptm0 entered promiscuous mode
Mon Dec  7 17:27:07 2015 daemon.notice netifd: Interface 'wan' is enabled
Mon Dec  7 17:27:07 2015 daemon.notice netifd: Interface 'wan' is setting up now
Mon Dec  7 17:27:07 2015 daemon.info pppd[3105]: Plugin rp-pppoe.so loaded.
Mon Dec  7 17:27:07 2015 daemon.info pppd[3105]: RP-PPPoE plugin version 3.8p compiled against pppd 2.4.7
Mon Dec  7 17:27:07 2015 daemon.notice pppd[3105]: pppd 2.4.7 started by root, uid 0
Mon Dec  7 17:27:22 2015 daemon.warn pppd[3105]: Timeout waiting for PADO packets
Mon Dec  7 17:27:22 2015 daemon.err pppd[3105]: Unable to complete PPPoE Discovery
Mon Dec  7 17:27:22 2015 daemon.info pppd[3105]: Exit.
Mon Dec  7 17:27:22 2015 daemon.notice netifd: Interface 'wan' is now down
Mon Dec  7 17:27:22 2015 kern.info kernel: [  105.308385] device ptm0 left promiscuous mode
Mon Dec  7 17:27:22 2015 daemon.notice netifd: Interface 'wan' is disabled
Mon Dec  7 17:27:22 2015 kern.info kernel: [  105.325049] device ptm0 entered promiscuous mode
Mon Dec  7 17:27:22 2015 daemon.notice netifd: Interface 'wan' is enabled
Mon Dec  7 17:27:22 2015 daemon.notice netifd: Interface 'wan' is setting up now
Mon Dec  7 17:27:23 2015 daemon.info pppd[4017]: Plugin rp-pppoe.so loaded.
Mon Dec  7 17:27:23 2015 daemon.info pppd[4017]: RP-PPPoE plugin version 3.8p compiled against pppd 2.4.7
Mon Dec  7 17:27:23 2015 daemon.notice pppd[4017]: pppd 2.4.7 started by root, uid 0
Mon Dec  7 17:27:28 2015 daemon.info pppd[4017]: PPP session is 1537
Mon Dec  7 17:27:28 2015 daemon.warn pppd[4017]: Connected to 88:a2:5e:17:be:be via interface ptm0.835
Mon Dec  7 17:27:28 2015 kern.info kernel: [  110.947277] pppoe-wan: renamed from ppp0
Mon Dec  7 17:27:28 2015 daemon.info pppd[4017]: Using interface pppoe-wan
Mon Dec  7 17:27:28 2015 daemon.notice pppd[4017]: Connect: pppoe-wan <--> ptm0.835
Mon Dec  7 17:27:28 2015 daemon.notice pppd[4017]: PAP authentication succeeded
Mon Dec  7 17:27:28 2015 daemon.notice pppd[4017]: peer from calling number 88:A2:5E:17:BE:BE authorized
Mon Dec  7 17:27:28 2015 daemon.notice netifd: Network device 'pppoe-wan' link is up
Mon Dec  7 17:27:28 2015 daemon.notice netifd: Interface 'wan6' has link connectivity 
Mon Dec  7 17:27:28 2015 daemon.notice pppd[4017]: local  IP address 95.238.236.181
Mon Dec  7 17:27:28 2015 daemon.notice pppd[4017]: remote IP address 192.168.100.1
Mon Dec  7 17:27:28 2015 daemon.notice pppd[4017]: primary   DNS address 85.37.17.17
Mon Dec  7 17:27:28 2015 daemon.notice pppd[4017]: secondary DNS address 85.38.28.72
Mon Dec  7 17:27:28 2015 daemon.notice pppd[4017]: local  LL address fe80::2836:e976:182d:2f96
Mon Dec  7 17:27:28 2015 daemon.notice pppd[4017]: remote LL address fe80::02a0:a50f:fc86:8004
Mon Dec  7 17:27:28 2015 daemon.info pppd[4017]: IPV6CP terminated by peer
Mon Dec  7 17:27:29 2015 daemon.notice netifd: Interface 'wan6' is enabled
Mon Dec  7 17:27:29 2015 daemon.notice netifd: Interface 'wan6' is setting up now
Mon Dec  7 17:27:29 2015 daemon.notice netifd: Interface 'wan' is now up
Mon Dec  7 17:27:29 2015 daemon.info dnsmasq[1376]: reading /tmp/resolv.conf.auto
Mon Dec  7 17:27:29 2015 daemon.info dnsmasq[1376]: using local addresses only for domain lan
Mon Dec  7 17:27:29 2015 daemon.info dnsmasq[1376]: using nameserver 85.37.17.17#53
Mon Dec  7 17:27:29 2015 daemon.info dnsmasq[1376]: using nameserver 85.38.28.72#53
Mon Dec  7 17:27:29 2015 daemon.notice netifd: Network alias 'pppoe-wan' link is up
Mon Dec  7 17:27:29 2015 daemon.notice netifd: Interface 'wan_6' is enabled
Mon Dec  7 17:27:29 2015 daemon.notice netifd: Interface 'wan_6' has link connectivity 
Mon Dec  7 17:27:29 2015 daemon.notice netifd: Interface 'wan_6' is setting up now
Mon Dec  7 17:27:29 2015 daemon.info dnsmasq[1376]: reading /tmp/resolv.conf.auto
Mon Dec  7 17:27:29 2015 daemon.info dnsmasq[1376]: using local addresses only for domain lan
Mon Dec  7 17:27:29 2015 daemon.info dnsmasq[1376]: using nameserver 85.37.17.17#53
Mon Dec  7 17:27:29 2015 daemon.info dnsmasq[1376]: using nameserver 85.38.28.72#53
Mon Dec  7 17:27:29 2015 daemon.info dnsmasq[1376]: using nameserver 85.37.17.17#53
Mon Dec  7 17:27:29 2015 daemon.info dnsmasq[1376]: using nameserver 85.38.28.72#53
Mon Dec  7 17:27:29 2015 daemon.notice netifd: Interface 'wan' has lost the connection
Mon Dec  7 17:27:29 2015 daemon.warn dnsmasq[1376]: no servers found in /tmp/resolv.conf.auto, will retry
Mon Dec  7 17:27:29 2015 daemon.notice netifd: Interface 'wan_6' is now down
Mon Dec  7 17:27:29 2015 daemon.notice netifd: Interface 'wan_6' is disabled
Mon Dec  7 17:27:29 2015 daemon.notice netifd: Interface 'wan_6' has link connectivity loss
Mon Dec  7 17:27:30 2015 user.notice firewall: Reloading firewall due to ifup of wan (pppoe-wan)
Mon Dec  7 17:27:33 2015 user.notice ddns-scripts[4579]: myddns_ipv4: PID '4579' started at 2015-12-07 17:27
Mon Dec  7 17:27:33 2015 user.warn ddns-scripts[4579]: myddns_ipv4: Service section disabled! - TERMINATE
Mon Dec  7 17:27:34 2015 user.warn ddns-scripts[4579]: myddns_ipv4: PID '4579' exit WITH ERROR '1' at 2015-12-07 17:27
Mon Dec  7 17:27:35 2015 daemon.err uhttpd[1243]: sh: write error: Broken pipe
Mon Dec  7 17:27:35 2015 daemon.err uhttpd[1243]: sh: write error: Broken pipe
Mon Dec  7 17:27:35 2015 daemon.err uhttpd[1243]: sh: write error: Broken pipe
Mon Dec  7 17:27:35 2015 daemon.err uhttpd[1243]: sh: write error: Broken pipe
Mon Dec  7 17:27:35 2015 daemon.err uhttpd[1243]: sh: write error: Broken pipe
Mon Dec  7 17:27:35 2015 daemon.err uhttpd[1243]: sh: write error: Broken pipe
Mon Dec  7 17:27:35 2015 daemon.err uhttpd[1243]: sh: write error: Broken pipe
Mon Dec  7 17:27:35 2015 daemon.err uhttpd[1243]: sh: write error: Broken pipe
Mon Dec  7 17:27:35 2015 daemon.err uhttpd[1243]: sh: write error: Broken pipe
Mon Dec  7 17:27:35 2015 daemon.err uhttpd[1243]: sh: write error: Broken pipe
Mon Dec  7 17:27:35 2015 daemon.err uhttpd[1243]: sh: write error: Broken pipe
Mon Dec  7 17:27:35 2015 daemon.err uhttpd[1243]: sh: write error: Broken pipe
Mon Dec  7 17:27:35 2015 daemon.err uhttpd[1243]: sh: write error: Broken pipe
Mon Dec  7 17:27:35 2015 daemon.err uhttpd[1243]: sh: write error: Broken pipe
Mon Dec  7 17:27:35 2015 daemon.err uhttpd[1243]: sh: write error: Broken pipe

Let me know it there is something else I can do in order to investigate deeper the problem

Thanks for the support
D.

comment:8 Changed 2 years ago by nbd

You have some SPI errors in your log, which I recently fixed. Please clean your kernel tree and do a fresh build with the latest version to see if they go away.

comment:9 in reply to: ↑ 7 Changed 2 years ago by mkresin

Replying to d.buffa@…:

The log says that DSL line is annex b (and it's strange, I'm in Italy and here annex is a), anyway i tried also with annex b firmare obtaining the same result...

It isn't strange. VDSL is (near to?) everywhere Annex B. Italy uses Annex A for ADSL.

Mon Dec  7 17:27:29 2015 daemon.notice netifd: Interface 'wan' has lost the connection

Here we go. Netifd - the OpenWrt networking daemon - seams to be your problem. Especially since your pppoe-wan interface is still up and usable (I assume the ppp process still runs).

I'm out of ideas. But nbd, the author of netifd btw., is your man.

Last edited 2 years ago by mkresin (previous) (diff)

comment:10 Changed 2 years ago by d.buffa@…

Tried now with a fresh and clean build from trunk. Unfortunately the situation is the same

[    0.000000] Linux version 4.1.13 (daniele@danfedvm.localdomain) (gcc version 5.2.0 (OpenWrt GCC 5.2.0 r47811) ) #1 Tue Dec 8 18:40:30 CET 2015
[    0.000000] SoC: VR9 rev 1.2
[    0.000000] bootconsole [early0] enabled
[    0.000000] CPU0 revision is: 00019556 (MIPS 34Kc)
[    0.000000] MIPS: machine is TDW8970 - TP-LINK TD-W8970
[    0.000000] Determined physical RAM map:
[    0.000000]  memory: 04000000 @ 00000000 (usable)
[    0.000000] Initrd not found or empty - disabling initrd
[    0.000000] Zone ranges:
[    0.000000]   Normal   [mem 0x0000000000000000-0x0000000003ffffff]
[    0.000000] Movable zone start for each node
[    0.000000] Early memory node ranges
[    0.000000]   node   0: [mem 0x0000000000000000-0x0000000003ffffff]
[    0.000000] Initmem setup node 0 [mem 0x0000000000000000-0x0000000003ffffff]
[    0.000000] On node 0 totalpages: 16384
[    0.000000] free_area_init_node: node 0, pgdat 804b1e00, node_mem_map 810078c0
[    0.000000]   Normal zone: 128 pages used for memmap
[    0.000000]   Normal zone: 0 pages reserved
[    0.000000]   Normal zone: 16384 pages, LIFO batch:3
[    0.000000] Primary instruction cache 32kB, VIPT, 4-way, linesize 32 bytes.
[    0.000000] Primary data cache 32kB, 4-way, VIPT, cache aliases, linesize 32 bytes
[    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: 16256
[    0.000000] Kernel command line: console=ttyLTQ0,115200 init=/etc/preinit
[    0.000000] PID hash table entries: 256 (order: -2, 1024 bytes)
[    0.000000] Dentry cache hash table entries: 8192 (order: 3, 32768 bytes)
[    0.000000] Inode-cache hash table entries: 4096 (order: 2, 16384 bytes)
[    0.000000] Writing ErrCtl register=00068a20
[    0.000000] Readback ErrCtl register=00068a20
[    0.000000] Memory: 59556K/65536K available (3771K kernel code, 149K rwdata, 988K rodata, 188K init, 207K bss, 5980K reserved, 0K cma-reserved)
[    0.000000] NR_IRQS:256
[    0.000000] CPU Clock: 500MHz
[    0.000000] clocksource MIPS: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041786 ns
[    0.000010] sched_clock: 32 bits at 250MHz, resolution 4ns, wraps every 8589934590ns
[    0.008194] Calibrating delay loop... 332.54 BogoMIPS (lpj=665088)
[    0.042319] pid_max: default: 32768 minimum: 301
[    0.047314] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes)
[    0.053785] Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes)
[    0.066669] clocksource jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns
[    0.076388] pinctrl core: initialized pinctrl subsystem
[    0.082206] NET: Registered protocol family 16
[    0.091075] pinctrl-xway 1e100b10.pinmux: Init done
[    0.096596] dma-xway 1e104100.dma: Init done - hw rev: 7, ports: 7, channels: 28
[    0.207055] dcdc-xrx200 1f106a00.dcdc: Core Voltage : 1016 mV
[    0.324903] ath9k,eeprom ath9k_eep: failed to load eeprom address
[    0.352958] usbcore: registered new interface driver usbfs
[    0.358452] usbcore: registered new interface driver hub
[    0.363820] usbcore: registered new device driver usb
[    0.369151] PCI host bridge to bus 0000:00
[    0.373139] pci_bus 0000:00: root bus resource [mem 0x1c000000-0x1cffffff]
[    0.380056] pci_bus 0000:00: root bus resource [io  0x1d800000-0x1d8fffff]
[    0.386998] pci_bus 0000:00: root bus resource [??? 0x00000000 flags 0x0]
[    0.393855] pci_bus 0000:00: No busn resource found for root bus, will use [bus 00-ff]
[    0.401880] pci 0000:00:00.0: [1bef:0011] type 01 class 0x060000
[    0.401904] ifx_pcie_rc_class_early_fixup: fixed pcie host bridge to pci-pci bridge
[    0.419640] pci 0000:00:00.0: PME# supported from D0 D3hot
[    0.420158] pci 0000:01:00.0: [168c:abcd] type 00 class 0x020000
[    0.420227] pci 0000:01:00.0: reg 0x10: [mem 0x00000000-0x0001ffff 64bit]
[    0.420299] pci 0000:01:00.0: reg 0x30: [mem 0x00000000-0x0000ffff pref]
[    0.420390] pci 0000:01:00.0: supports D1
[    0.420411] pci 0000:01:00.0: PME# supported from D0 D1 D3hot
[    0.420668] pci_bus 0000:01: busn_res: [bus 01-ff] end is updated to 01
[    0.420705] pci_bus 0000:00: busn_res: [bus 00-ff] end is updated to 01
[    0.420763] pci 0000:00:00.0: BAR 8: assigned [mem 0x1c000000-0x1c0fffff]
[    0.427439] pci 0000:00:00.0: BAR 9: assigned [mem 0x1c100000-0x1c1fffff pref]
[    0.434708] pci 0000:01:00.0: BAR 0: assigned [mem 0x1c000000-0x1c01ffff 64bit]
[    0.442107] pci 0000:01:00.0: BAR 6: assigned [mem 0x1c100000-0x1c10ffff pref]
[    0.449370] pci 0000:00:00.0: PCI bridge to [bus 01]
[    0.454407] pci 0000:00:00.0:   bridge window [mem 0x1c000000-0x1c0fffff]
[    0.461263] pci 0000:00:00.0:   bridge window [mem 0x1c100000-0x1c1fffff pref]
[    0.468571] ifx_pcie_bios_map_irq port 0 dev 0000:00:00.0 slot 0 pin 1 
[    0.475227] ifx_pcie_bios_map_irq dev 0000:00:00.0 irq 144 assigned
[    0.481578] ifx_pcie_bios_map_irq port 0 dev 0000:01:00.0 slot 0 pin 1 
[    0.488245] ifx_pcie_bios_map_irq dev 0000:01:00.0 irq 144 assigned
[    0.495538] Switched to clocksource MIPS
[    0.501127] NET: Registered protocol family 2
[    0.506522] TCP established hash table entries: 1024 (order: 0, 4096 bytes)
[    0.513409] TCP bind hash table entries: 1024 (order: 0, 4096 bytes)
[    0.519784] TCP: Hash tables configured (established 1024 bind 1024)
[    0.526356] UDP hash table entries: 256 (order: 0, 4096 bytes)
[    0.532138] UDP-Lite hash table entries: 256 (order: 0, 4096 bytes)
[    0.538836] NET: Registered protocol family 1
[    0.543206] PCI: CLS 0 bytes, default 32
[    0.543622] gptu: totally 6 16-bit timers/counters
[    0.548421] gptu: misc_register on minor 63
[    0.552555] gptu: succeeded to request irq 126
[    0.557047] gptu: succeeded to request irq 127
[    0.561579] gptu: succeeded to request irq 128
[    0.566079] gptu: succeeded to request irq 129
[    0.570589] gptu: succeeded to request irq 130
[    0.575103] gptu: succeeded to request irq 131
[    0.580483] phy-xrx200 gphy-xrx200: requesting lantiq/vr9_phy11g_a2x.bin
[    0.587965] phy-xrx200 gphy-xrx200: booting GPHY0 firmware at 3A00000
[    0.594285] phy-xrx200 gphy-xrx200: booting GPHY1 firmware at 3A00000
[    0.702080] futex hash table entries: 256 (order: -1, 3072 bytes)
[    0.717185] squashfs: version 4.0 (2009/01/31) Phillip Lougher
[    0.722922] jffs2: version 2.2 (NAND) (SUMMARY) (LZMA) (RTIME) (CMODE_PRIORITY) (c) 2001-2006 Red Hat, Inc.
[    0.734475] io scheduler noop registered
[    0.738320] io scheduler deadline registered (default)
[    0.744216] 1e100c00.serial: ttyLTQ0 at MMIO 0x1e100c00 (irq = 112, base_baud = 0) is a lantiq,asc
[    0.753107] console [ttyLTQ0] enabled
[    0.760440] bootconsole [early0] disabled
[    0.773515] m25p80 spi32766.3: s25fl064k (8192 Kbytes)
[    0.777281] 4 ofpart partitions found on MTD device spi32766.3
[    0.783055] Creating 4 MTD partitions on "spi32766.3":
[    0.788198] 0x000000000000-0x000000020000 : "u-boot"
[    0.794266] 0x000000020000-0x0000007c0000 : "firmware"
[    0.800997] 0x0000001bc8ac-0x0000007c0000 : "rootfs"
[    0.805635] mtd: device 2 (rootfs) set to be root filesystem
[    0.811085] 1 squashfs-split partitions found on MTD device rootfs
[    0.816411] 0x0000005b0000-0x0000007c0000 : "rootfs_data"
[    0.893694] 0x0000007c0000-0x0000007d0000 : "config"
[    0.898341] 0x0000007d0000-0x000000800000 : "boardconfig"
[    0.903830] Lantiq SoC SPI controller rev 8 (TXFS 8, RXFS 8, DMA 1)
[    1.011373] libphy: lantiq,xrx200-mdio: probed
[    1.084272] eth0: attached PHY [Lantiq XWAY PEF7071] (phy_addr=0:00, irq=-1)
[    1.152244] eth0: attached PHY [Lantiq XWAY PEF7071] (phy_addr=0:05, irq=-1)
[    1.220235] eth0: attached PHY [Lantiq XWAY VR9 GPHY 11G v1.4] (phy_addr=0:11, irq=-1)
[    1.288227] eth0: attached PHY [Lantiq XWAY VR9 GPHY 11G v1.4] (phy_addr=0:13, irq=-1)
[    1.296097] wdt 1f8803f0.watchdog: Init done
[    1.300948] NET: Registered protocol family 10
[    1.307405] NET: Registered protocol family 17
[    1.310537] bridge: automatic filtering via arp/ip/ip6tables has been deprecated. Update your scripts to load br_netfilter if you need this.
[    1.323067] 8021q: 802.1Q VLAN Support v1.8
[    1.329564] ath9k,eeprom ath9k_eep: using led pin 0.
[    1.333119] ath9k,eeprom ath9k_eep: loaded ath9k eeprom
[    1.342588] UBIFS error (pid: 1): cannot open "ubi0:rootfs", error -19
[    1.353342] VFS: Mounted root (squashfs filesystem) readonly on device 31:2.
[    1.359845] Freeing unused kernel memory: 188K (804d1000 - 80500000)
[    1.388599] random: nonblocking pool is initialized
[    2.659604] init: Console is alive
[    2.661845] init: - watchdog -
[    4.213028] dwc2 1e101000.ifxhcd: requested GPIO 489
[    4.247688] eth0: port 2 got link
[    5.071733] dwc2 1e101000.ifxhcd: DWC OTG Controller
[    5.075303] dwc2 1e101000.ifxhcd: new USB bus registered, assigned bus number 1
[    5.082641] dwc2 1e101000.ifxhcd: irq 62, io mem 0x00000000
[    5.088166] dwc2 1e101000.ifxhcd: Hardware does not support descriptor DMA mode -
[    5.095608] dwc2 1e101000.ifxhcd: falling back to buffer DMA mode.
[    5.102936] hub 1-0:1.0: USB hub found
[    5.106029] hub 1-0:1.0: 1 port detected
[    5.967742] dwc2 1e106000.ifxhcd: DWC OTG Controller
[    5.971310] dwc2 1e106000.ifxhcd: new USB bus registered, assigned bus number 2
[    5.978647] dwc2 1e106000.ifxhcd: irq 91, io mem 0x00000000
[    5.984172] dwc2 1e106000.ifxhcd: Hardware does not support descriptor DMA mode -
[    5.991615] dwc2 1e106000.ifxhcd: falling back to buffer DMA mode.
[    5.998963] hub 2-0:1.0: USB hub found
[    6.002021] hub 2-0:1.0: 1 port detected
[    6.731671] init: - preinit -
[   10.530063] jffs2: notice: (298) jffs2_build_xattr_subsystem: complete building xattr subsystem, 0 of xdatum (0 unchecked, 0 orphan) and 0 of xref (0 dead, 0 orphan) found.
[   10.546561] mount_root: switching to jffs2 overlay
[   10.586775] procd: - early -
[   10.588536] procd: - watchdog -
[   11.247576] eth0: port 2 lost link
[   11.364963] procd: - ubus -
[   12.379615] procd: - init -
[   13.344688] IFXOS, Version 1.5.19 (c) Copyright 2009, Lantiq Deutschland GmbH
[   13.354713] NET: Registered protocol family 8
[   13.357676] NET: Registered protocol family 20
[   13.366837] tun: Universal TUN/TAP device driver, 1.6
[   13.370497] tun: (C) 1999-2004 Max Krasnyansky <maxk@qualcomm.com>
[   13.394224] PPP generic driver version 2.4.2
[   13.413103] ip6_tables: (C) 2000-2006 Netfilter Core Team
[   13.454550] Lantiq (VRX) DSL CPE MEI driver, version 1.4.8.5, (c) 2013 Lantiq Deutschland GmbH
[   13.454550] 
[   13.454550] Lantiq CPE API Driver version: DSL CPE API V4.16.6.3
[   13.509999] 
[   13.509999] Predefined debug level: 3
[   13.551459] u32 classifier
[   13.552764]     input device check on
[   13.556377]     Actions configured
[   13.583813] Mirror/redirect action on
[   13.607263] nf_conntrack version 0.5.0 (933 buckets, 3732 max)
[   13.620951] Loading modules backported from Linux version master-2015-12-03-0-g1677f62
[   13.627479] Backport generated by backports.git backports-20151120-0-g906a6b3
[   13.638273] ip_tables: (C) 2000-2006 Netfilter Core Team
[   13.650924] Infineon Technologies DEU driver version 2.0.0 
[   13.656837] IFX DEU DES initialized (multiblock).
[   13.661047] IFX DEU AES initialized (multiblock).
[   13.665213] IFX DEU ARC4 initialized (multiblock).
[   13.669847] IFX DEU SHA1 initialized.
[   13.673452] IFX DEU MD5 initialized.
[   13.677046] IFX DEU SHA1_HMAC initialized.
[   13.681165] IFX DEU MD5_HMAC initialized.
[   13.705371] NET: Registered protocol family 24
[   13.753082] xt_time: kernel timezone is -0000
[   13.790083] cfg80211: World regulatory domain updated:
[   13.793833] cfg80211:  DFS Master region: unset
[   13.798142] cfg80211:   (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp), (dfs_cac_time)
[   13.807903] cfg80211:   (2402000 KHz - 2472000 KHz @ 40000 KHz), (N/A, 2000 mBm), (N/A)
[   13.815908] cfg80211:   (2457000 KHz - 2482000 KHz @ 40000 KHz), (N/A, 2000 mBm), (N/A)
[   13.823916] cfg80211:   (2474000 KHz - 2494000 KHz @ 20000 KHz), (N/A, 2000 mBm), (N/A)
[   13.831927] cfg80211:   (5170000 KHz - 5250000 KHz @ 80000 KHz, 160000 KHz AUTO), (N/A, 2000 mBm), (N/A)
[   13.841425] cfg80211:   (5250000 KHz - 5330000 KHz @ 80000 KHz, 160000 KHz AUTO), (N/A, 2000 mBm), (0 s)
[   13.850901] cfg80211:   (5490000 KHz - 5730000 KHz @ 160000 KHz), (N/A, 2000 mBm), (0 s)
[   13.858993] cfg80211:   (5735000 KHz - 5835000 KHz @ 80000 KHz), (N/A, 2000 mBm), (N/A)
[   13.867001] cfg80211:   (57240000 KHz - 63720000 KHz @ 2160000 KHz), (N/A, 0 mBm), (N/A)
[   13.991046] PCI: Enabling device 0000:00:00.0 (0000 -> 0002)
[   13.995381] PCI: Enabling device 0000:01:00.0 (0000 -> 0002)
[   14.005901] ath: EEPROM regdomain: 0x0
[   14.005925] ath: EEPROM indicates default country code should be used
[   14.005936] ath: doing EEPROM country->regdmn map search
[   14.005958] ath: country maps to regdmn code: 0x3a
[   14.005970] ath: Country alpha2 being used: US
[   14.005981] ath: Regpair used: 0x3a
[   14.015503] ieee80211 phy0: Selected rate control algorithm 'minstrel_ht'
[   14.018883] ieee80211 phy0: Atheros AR9300 Rev:3 mem=0xbc000000, irq=144
[   14.027149] cfg80211: Regulatory domain changed to country: US
[   14.031605] cfg80211:  DFS Master region: FCC
[   14.035735] cfg80211:   (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp), (dfs_cac_time)
[   14.045495] cfg80211:   (2402000 KHz - 2472000 KHz @ 40000 KHz), (N/A, 3000 mBm), (N/A)
[   14.053505] cfg80211:   (5170000 KHz - 5250000 KHz @ 80000 KHz, 160000 KHz AUTO), (N/A, 2300 mBm), (N/A)
[   14.062992] cfg80211:   (5250000 KHz - 5330000 KHz @ 80000 KHz, 160000 KHz AUTO), (N/A, 2300 mBm), (0 s)
[   14.072477] cfg80211:   (5490000 KHz - 5730000 KHz @ 160000 KHz), (N/A, 2300 mBm), (0 s)
[   14.080571] cfg80211:   (5735000 KHz - 5835000 KHz @ 80000 KHz), (N/A, 3000 mBm), (N/A)
[   14.088578] cfg80211:   (57240000 KHz - 63720000 KHz @ 2160000 KHz), (N/A, 4000 mBm), (N/A)
[   20.608799] PTM 1.0.27    PTM (E1) firmware version 0.30
[   20.612723] ifxmips_ptm: PTM init succeed
[   25.996997] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
[   26.033355] device eth0.1 entered promiscuous mode
[   26.036812] device eth0 entered promiscuous mode
[   26.064981] IPv6: ADDRCONF(NETDEV_UP): br-lan: link is not ready
[   26.451807] eth0: port 2 got link
[   26.453833] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
[   26.467819] br-lan: port 1(eth0.1) entered forwarding state
[   26.472052] br-lan: port 1(eth0.1) entered forwarding state
[   26.509071] IPv6: ADDRCONF(NETDEV_CHANGE): br-lan: link becomes ready
[   27.452351] br-lan: port 1(eth0.1) entered disabled state
[   28.452518] br-lan: port 1(eth0.1) entered forwarding state
[   28.456753] br-lan: port 1(eth0.1) entered forwarding state
[   30.459606] br-lan: port 1(eth0.1) entered forwarding state
[   70.239113] enter showtime
[   70.244358] enter showtime
[  139.674437] device ptm0 entered promiscuous mode
[  141.291995] pppoe-wan: renamed from ppp0
Tue Dec  8 17:34:24 2015 user.info kernel: [   10.586775] procd: - early -
Tue Dec  8 17:34:24 2015 user.info kernel: [   10.588536] procd: - watchdog -
Tue Dec  8 17:34:24 2015 kern.info kernel: [   11.247576] eth0: port 2 lost link
Tue Dec  8 17:34:24 2015 user.info kernel: [   11.364963] procd: - ubus -
Tue Dec  8 17:34:24 2015 user.info kernel: [   12.379615] procd: - init -
Tue Dec  8 17:34:24 2015 kern.info kernel: [   13.344688] IFXOS, Version 1.5.19 (c) Copyright 2009, Lantiq Deutschland GmbH
Tue Dec  8 17:34:24 2015 kern.info kernel: [   13.354713] NET: Registered protocol family 8
Tue Dec  8 17:34:24 2015 kern.info kernel: [   13.357676] NET: Registered protocol family 20
Tue Dec  8 17:34:24 2015 kern.info kernel: [   13.366837] tun: Universal TUN/TAP device driver, 1.6
Tue Dec  8 17:34:24 2015 kern.info kernel: [   13.370497] tun: (C) 1999-2004 Max Krasnyansky <maxk@qualcomm.com>
Tue Dec  8 17:34:24 2015 kern.info kernel: [   13.394224] PPP generic driver version 2.4.2
Tue Dec  8 17:34:24 2015 kern.info kernel: [   13.413103] ip6_tables: (C) 2000-2006 Netfilter Core Team
Tue Dec  8 17:34:24 2015 kern.info kernel: [   13.454550] Lantiq (VRX) DSL CPE MEI driver, version 1.4.8.5, (c) 2013 Lantiq Deutschland GmbH
Tue Dec  8 17:34:24 2015 kern.info kernel: [   13.454550] 
Tue Dec  8 17:34:24 2015 kern.info kernel: [   13.454550] Lantiq CPE API Driver version: DSL CPE API V4.16.6.3
Tue Dec  8 17:34:24 2015 kern.warn kernel: [   13.509999] 
Tue Dec  8 17:34:24 2015 kern.warn kernel: [   13.509999] Predefined debug level: 3
Tue Dec  8 17:34:24 2015 kern.info kernel: [   13.551459] u32 classifier
Tue Dec  8 17:34:24 2015 kern.info kernel: [   13.552764]     input device check on
Tue Dec  8 17:34:24 2015 kern.info kernel: [   13.556377]     Actions configured
Tue Dec  8 17:34:24 2015 kern.info kernel: [   13.583813] Mirror/redirect action on
Tue Dec  8 17:34:24 2015 kern.info kernel: [   13.607263] nf_conntrack version 0.5.0 (933 buckets, 3732 max)
Tue Dec  8 17:34:24 2015 kern.info kernel: [   13.620951] Loading modules backported from Linux version master-2015-12-03-0-g1677f62
Tue Dec  8 17:34:24 2015 kern.info kernel: [   13.627479] Backport generated by backports.git backports-20151120-0-g906a6b3
Tue Dec  8 17:34:24 2015 kern.info kernel: [   13.638273] ip_tables: (C) 2000-2006 Netfilter Core Team
Tue Dec  8 17:34:24 2015 kern.info kernel: [   13.650924] Infineon Technologies DEU driver version 2.0.0 
Tue Dec  8 17:34:24 2015 kern.notice kernel: [   13.656837] IFX DEU DES initialized (multiblock).
Tue Dec  8 17:34:24 2015 kern.notice kernel: [   13.661047] IFX DEU AES initialized (multiblock).
Tue Dec  8 17:34:24 2015 kern.notice kernel: [   13.665213] IFX DEU ARC4 initialized (multiblock).
Tue Dec  8 17:34:24 2015 kern.notice kernel: [   13.669847] IFX DEU SHA1 initialized.
Tue Dec  8 17:34:24 2015 kern.notice kernel: [   13.673452] IFX DEU MD5 initialized.
Tue Dec  8 17:34:24 2015 kern.notice kernel: [   13.677046] IFX DEU SHA1_HMAC initialized.
Tue Dec  8 17:34:24 2015 kern.notice kernel: [   13.681165] IFX DEU MD5_HMAC initialized.
Tue Dec  8 17:34:24 2015 kern.info kernel: [   13.705371] NET: Registered protocol family 24
Tue Dec  8 17:34:24 2015 kern.info kernel: [   13.753082] xt_time: kernel timezone is -0000
Tue Dec  8 17:34:24 2015 kern.info kernel: [   13.790083] cfg80211: World regulatory domain updated:
Tue Dec  8 17:34:24 2015 kern.info kernel: [   13.793833] cfg80211:  DFS Master region: unset
Tue Dec  8 17:34:24 2015 kern.info kernel: [   13.798142] cfg80211:   (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp), (dfs_cac_time)
Tue Dec  8 17:34:24 2015 kern.info kernel: [   13.807903] cfg80211:   (2402000 KHz - 2472000 KHz @ 40000 KHz), (N/A, 2000 mBm), (N/A)
Tue Dec  8 17:34:24 2015 kern.info kernel: [   13.815908] cfg80211:   (2457000 KHz - 2482000 KHz @ 40000 KHz), (N/A, 2000 mBm), (N/A)
Tue Dec  8 17:34:24 2015 kern.info kernel: [   13.823916] cfg80211:   (2474000 KHz - 2494000 KHz @ 20000 KHz), (N/A, 2000 mBm), (N/A)
Tue Dec  8 17:34:24 2015 kern.info kernel: [   13.831927] cfg80211:   (5170000 KHz - 5250000 KHz @ 80000 KHz, 160000 KHz AUTO), (N/A, 2000 mBm), (N/A)
Tue Dec  8 17:34:24 2015 kern.info kernel: [   13.841425] cfg80211:   (5250000 KHz - 5330000 KHz @ 80000 KHz, 160000 KHz AUTO), (N/A, 2000 mBm), (0 s)
Tue Dec  8 17:34:24 2015 kern.info kernel: [   13.850901] cfg80211:   (5490000 KHz - 5730000 KHz @ 160000 KHz), (N/A, 2000 mBm), (0 s)
Tue Dec  8 17:34:24 2015 kern.info kernel: [   13.858993] cfg80211:   (5735000 KHz - 5835000 KHz @ 80000 KHz), (N/A, 2000 mBm), (N/A)
Tue Dec  8 17:34:24 2015 kern.info kernel: [   13.867001] cfg80211:   (57240000 KHz - 63720000 KHz @ 2160000 KHz), (N/A, 0 mBm), (N/A)
Tue Dec  8 17:34:24 2015 kern.warn kernel: [   13.991046] PCI: Enabling device 0000:00:00.0 (0000 -> 0002)
Tue Dec  8 17:34:24 2015 kern.warn kernel: [   13.995381] PCI: Enabling device 0000:01:00.0 (0000 -> 0002)
Tue Dec  8 17:34:24 2015 kern.debug kernel: [   14.005901] ath: EEPROM regdomain: 0x0
Tue Dec  8 17:34:24 2015 kern.debug kernel: [   14.005925] ath: EEPROM indicates default country code should be used
Tue Dec  8 17:34:24 2015 kern.debug kernel: [   14.005936] ath: doing EEPROM country->regdmn map search
Tue Dec  8 17:34:24 2015 kern.debug kernel: [   14.005958] ath: country maps to regdmn code: 0x3a
Tue Dec  8 17:34:24 2015 kern.debug kernel: [   14.005970] ath: Country alpha2 being used: US
Tue Dec  8 17:34:24 2015 kern.debug kernel: [   14.005981] ath: Regpair used: 0x3a
Tue Dec  8 17:34:24 2015 kern.debug kernel: [   14.015503] ieee80211 phy0: Selected rate control algorithm 'minstrel_ht'
Tue Dec  8 17:34:24 2015 kern.info kernel: [   14.018883] ieee80211 phy0: Atheros AR9300 Rev:3 mem=0xbc000000, irq=144
Tue Dec  8 17:34:24 2015 kern.info kernel: [   14.027149] cfg80211: Regulatory domain changed to country: US
Tue Dec  8 17:34:24 2015 kern.info kernel: [   14.031605] cfg80211:  DFS Master region: FCC
Tue Dec  8 17:34:24 2015 kern.info kernel: [   14.035735] cfg80211:   (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp), (dfs_cac_time)
Tue Dec  8 17:34:24 2015 kern.info kernel: [   14.045495] cfg80211:   (2402000 KHz - 2472000 KHz @ 40000 KHz), (N/A, 3000 mBm), (N/A)
Tue Dec  8 17:34:24 2015 kern.info kernel: [   14.053505] cfg80211:   (5170000 KHz - 5250000 KHz @ 80000 KHz, 160000 KHz AUTO), (N/A, 2300 mBm), (N/A)
Tue Dec  8 17:34:24 2015 kern.info kernel: [   14.062992] cfg80211:   (5250000 KHz - 5330000 KHz @ 80000 KHz, 160000 KHz AUTO), (N/A, 2300 mBm), (0 s)
Tue Dec  8 17:34:24 2015 kern.info kernel: [   14.072477] cfg80211:   (5490000 KHz - 5730000 KHz @ 160000 KHz), (N/A, 2300 mBm), (0 s)
Tue Dec  8 17:34:24 2015 kern.info kernel: [   14.080571] cfg80211:   (5735000 KHz - 5835000 KHz @ 80000 KHz), (N/A, 3000 mBm), (N/A)
Tue Dec  8 17:34:24 2015 kern.info kernel: [   14.088578] cfg80211:   (57240000 KHz - 63720000 KHz @ 2160000 KHz), (N/A, 4000 mBm), (N/A)
Tue Dec  8 17:34:26 2015 user.notice : 'radio0' is disabled
Tue Dec  8 17:34:26 2015 user.notice : 'radio0' is disabled
Tue Dec  8 17:34:28 2015 kern.info kernel: [   20.608799] PTM 1.0.27    PTM (E1) firmware version 0.30
Tue Dec  8 17:34:28 2015 kern.warn kernel: [   20.612723] ifxmips_ptm: PTM init succeed
Tue Dec  8 17:34:30 2015 authpriv.info dropbear[899]: Not backgrounding
Tue Dec  8 17:34:32 2015 daemon.err insmod: module is already loaded - xt_multiport
Tue Dec  8 17:34:32 2015 daemon.err insmod: module is already loaded - xt_comment
Tue Dec  8 17:34:32 2015 daemon.err insmod: module is already loaded - xt_length
Tue Dec  8 17:34:33 2015 kern.info kernel: [   25.996997] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
Tue Dec  8 17:34:33 2015 kern.info kernel: [   26.033355] device eth0.1 entered promiscuous mode
Tue Dec  8 17:34:33 2015 kern.info kernel: [   26.036812] device eth0 entered promiscuous mode
Tue Dec  8 17:34:33 2015 kern.info kernel: [   26.064981] IPv6: ADDRCONF(NETDEV_UP): br-lan: link is not ready
Tue Dec  8 17:34:33 2015 daemon.notice netifd: Interface 'lan' is enabled
Tue Dec  8 17:34:33 2015 daemon.notice netifd: Interface 'lan' is setting up now
Tue Dec  8 17:34:33 2015 daemon.notice netifd: Interface 'lan' is now up
Tue Dec  8 17:34:33 2015 daemon.notice netifd: Interface 'loopback' is enabled
Tue Dec  8 17:34:33 2015 daemon.notice netifd: Interface 'loopback' is setting up now
Tue Dec  8 17:34:33 2015 daemon.notice netifd: Interface 'loopback' is now up
Tue Dec  8 17:34:33 2015 daemon.notice netifd: Network device 'lo' link is up
Tue Dec  8 17:34:33 2015 daemon.notice netifd: Interface 'loopback' has link connectivity 
Tue Dec  8 17:34:34 2015 kern.info kernel: [   26.451807] eth0: port 2 got link
Tue Dec  8 17:34:34 2015 kern.info kernel: [   26.453833] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
Tue Dec  8 17:34:34 2015 daemon.notice netifd: VLAN 'eth0.1' link is up
Tue Dec  8 17:34:34 2015 daemon.notice netifd: Network device 'eth0' link is up
Tue Dec  8 17:34:34 2015 kern.info kernel: [   26.467819] br-lan: port 1(eth0.1) entered forwarding state
Tue Dec  8 17:34:34 2015 kern.info kernel: [   26.472052] br-lan: port 1(eth0.1) entered forwarding state
Tue Dec  8 17:34:34 2015 daemon.notice netifd: Bridge 'br-lan' link is up
Tue Dec  8 17:34:34 2015 daemon.notice netifd: Interface 'lan' has link connectivity 
Tue Dec  8 17:34:34 2015 kern.info kernel: [   26.509071] IPv6: ADDRCONF(NETDEV_CHANGE): br-lan: link becomes ready
Tue Dec  8 17:34:35 2015 daemon.err insmod: module is already loaded - xt_multiport
Tue Dec  8 17:34:35 2015 daemon.notice netifd: Network device 'eth0' link is down
Tue Dec  8 17:34:35 2015 daemon.notice netifd: VLAN 'eth0.1' link is down
Tue Dec  8 17:34:35 2015 kern.info kernel: [   27.452351] br-lan: port 1(eth0.1) entered disabled state
Tue Dec  8 17:34:35 2015 daemon.err insmod: module is already loaded - xt_comment
Tue Dec  8 17:34:35 2015 daemon.err insmod: module is already loaded - xt_length
Tue Dec  8 17:34:35 2015 user.notice firewall: Reloading firewall due to ifup of lan (br-lan)
Tue Dec  8 17:34:35 2015 user.notice : iptables: Resource temporarily unavailable.
Tue Dec  8 17:34:36 2015 kern.info kernel: [   28.452518] br-lan: port 1(eth0.1) entered forwarding state
Tue Dec  8 17:34:36 2015 kern.info kernel: [   28.456753] br-lan: port 1(eth0.1) entered forwarding state
Tue Dec  8 17:34:36 2015 daemon.notice netifd: Network device 'eth0' link is up
Tue Dec  8 17:34:36 2015 daemon.notice netifd: VLAN 'eth0.1' link is up
Tue Dec  8 17:34:38 2015 kern.info kernel: [   30.459606] br-lan: port 1(eth0.1) entered forwarding state
Tue Dec  8 17:34:38 2015 daemon.info zabbix_agentd[1251]: Starting Zabbix Agent [OpenWrt]. Zabbix 2.4.6 (revision 54796).
Tue Dec  8 17:34:38 2015 daemon.info zabbix_agentd[1251]: using configuration file: /etc/zabbix_agentd.conf
Tue Dec  8 17:34:38 2015 daemon.info zabbix_agentd[1251]: agent #0 started [main process]
Tue Dec  8 17:34:38 2015 daemon.info zabbix_agentd[1261]: agent #2 started [listener #1]
Tue Dec  8 17:34:38 2015 daemon.info zabbix_agentd[1260]: agent #1 started [collector]
Tue Dec  8 17:34:38 2015 user.notice : setting up led usb
Tue Dec  8 17:34:38 2015 user.notice : setting up led usb2
Tue Dec  8 17:34:38 2015 user.notice : setting up led internet
Tue Dec  8 17:34:38 2015 user.notice : setting up led dsl
Tue Dec  8 17:34:38 2015 user.notice : setting up led wps
Tue Dec  8 17:34:39 2015 daemon.info procd: - init complete -
Tue Dec  8 17:34:41 2015 daemon.info dnsmasq[1351]: started, version 2.75 cachesize 150
Tue Dec  8 17:34:41 2015 daemon.info dnsmasq[1351]: compile time options: IPv6 GNU-getopt no-DBus no-i18n no-IDN DHCP no-DHCPv6 no-Lua TFTP no-conntrack no-ipset no-auth no-DNSSEC loop-detect inotify
Tue Dec  8 17:34:41 2015 daemon.info dnsmasq[1351]: DNS service limited to local subnets
Tue Dec  8 17:34:41 2015 daemon.info dnsmasq-dhcp[1351]: DHCP, IP range 192.168.1.100 -- 192.168.1.249, lease time 12h
Tue Dec  8 17:34:41 2015 daemon.info dnsmasq[1351]: using local addresses only for domain lan
Tue Dec  8 17:34:41 2015 daemon.warn dnsmasq[1351]: no servers found in /tmp/resolv.conf.auto, will retry
Tue Dec  8 17:34:41 2015 daemon.info dnsmasq[1351]: read /etc/hosts - 4 addresses
Tue Dec  8 17:34:41 2015 daemon.info dnsmasq[1351]: read /tmp/hosts/dhcp - 1 addresses
Tue Dec  8 17:34:41 2015 daemon.info dnsmasq-dhcp[1351]: read /etc/ethers - 0 addresses
Tue Dec  8 17:34:42 2015 daemon.warn odhcpd[810]: DHCPV6 SOLICIT IA_NA from 000100011d63f4e488ae1d577e55 on br-lan: ok fd86:be0e:6396::c2c/128 
Tue Dec  8 17:34:42 2015 daemon.warn odhcpd[810]: DHCPV6 REQUEST IA_NA from 000100011d63f4e488ae1d577e55 on br-lan: ok fd86:be0e:6396::c2c/128 
Tue Dec  8 17:34:43 2015 daemon.info dnsmasq-dhcp[1351]: DHCPREQUEST(br-lan) 192.168.1.139 88:ae:1d:57:7e:55 
Tue Dec  8 17:34:43 2015 daemon.info dnsmasq-dhcp[1351]: DHCPACK(br-lan) 192.168.1.139 88:ae:1d:57:7e:55 dan10alm
Tue Dec  8 17:34:44 2015 daemon.warn odhcpd[810]: DHCPV6 CONFIRM IA_NA from 000100011d63f4e488ae1d577e55 on br-lan: not on-link fd86:be0e:6396::c2c/128 
Tue Dec  8 17:34:44 2015 daemon.warn odhcpd[810]: DHCPV6 SOLICIT IA_NA from 000100011d63f4e488ae1d577e55 on br-lan: ok fd86:be0e:6396::c2c/128 
Tue Dec  8 17:34:44 2015 daemon.info dnsmasq[1351]: read /etc/hosts - 4 addresses
Tue Dec  8 17:34:44 2015 daemon.info dnsmasq[1351]: read /tmp/hosts/odhcpd - 0 addresses
Tue Dec  8 17:34:44 2015 daemon.info dnsmasq[1351]: read /tmp/hosts/dhcp - 1 addresses
Tue Dec  8 17:34:44 2015 daemon.info dnsmasq-dhcp[1351]: read /etc/ethers - 0 addresses
Tue Dec  8 17:34:45 2015 daemon.warn odhcpd[810]: DHCPV6 REQUEST IA_NA from 000100011d63f4e488ae1d577e55 on br-lan: ok fd86:be0e:6396::c2c/128 
Tue Dec  8 17:34:45 2015 daemon.info dnsmasq[1351]: read /etc/hosts - 4 addresses
Tue Dec  8 17:34:45 2015 daemon.info dnsmasq[1351]: read /tmp/hosts/odhcpd - 1 addresses
Tue Dec  8 17:34:45 2015 daemon.info dnsmasq[1351]: read /tmp/hosts/dhcp - 1 addresses
Tue Dec  8 17:34:45 2015 daemon.info dnsmasq-dhcp[1351]: read /etc/ethers - 0 addresses
Tue Dec  8 17:35:18 2015 kern.warn kernel: [   70.239113] enter showtime
Tue Dec  8 17:35:18 2015 kern.warn kernel: [   70.244358] enter showtime
Tue Dec  8 17:36:27 2015 kern.info kernel: [  139.674437] device ptm0 entered promiscuous mode
Tue Dec  8 17:36:27 2015 daemon.notice netifd: Interface 'wan' is enabled
Tue Dec  8 17:36:27 2015 daemon.notice netifd: Network device 'ptm0' link is up
Tue Dec  8 17:36:27 2015 daemon.notice netifd: VLAN 'ptm0.835' link is up
Tue Dec  8 17:36:27 2015 daemon.notice netifd: Interface 'wan' has link connectivity 
Tue Dec  8 17:36:27 2015 daemon.notice netifd: Interface 'wan' is setting up now
Tue Dec  8 17:36:28 2015 daemon.info pppd[4842]: Plugin rp-pppoe.so loaded.
Tue Dec  8 17:36:28 2015 daemon.info pppd[4842]: RP-PPPoE plugin version 3.8p compiled against pppd 2.4.7
Tue Dec  8 17:36:28 2015 daemon.notice pppd[4842]: pppd 2.4.7 started by root, uid 0
Tue Dec  8 17:36:29 2015 daemon.info pppd[4842]: PPP session is 5171
Tue Dec  8 17:36:29 2015 daemon.warn pppd[4842]: Connected to 88:a2:5e:17:be:be via interface ptm0.835
Tue Dec  8 17:36:29 2015 kern.info kernel: [  141.291995] pppoe-wan: renamed from ppp0
Tue Dec  8 17:36:29 2015 daemon.info pppd[4842]: Using interface pppoe-wan
Tue Dec  8 17:36:29 2015 daemon.notice pppd[4842]: Connect: pppoe-wan <--> ptm0.835
Tue Dec  8 17:36:29 2015 daemon.info pppd[4842]: Remote message: REASON021 - TK MISSING: NOT IN DSL-LINE
Tue Dec  8 17:36:29 2015 daemon.notice pppd[4842]: PAP authentication succeeded
Tue Dec  8 17:36:29 2015 daemon.notice pppd[4842]: peer from calling number 88:A2:5E:17:BE:BE authorized
Tue Dec  8 17:36:29 2015 daemon.notice netifd: Network device 'pppoe-wan' link is up
Tue Dec  8 17:36:29 2015 daemon.notice netifd: Interface 'wan6' has link connectivity 
Tue Dec  8 17:36:29 2015 daemon.notice pppd[4842]: local  IP address 79.36.225.202
Tue Dec  8 17:36:29 2015 daemon.notice pppd[4842]: remote IP address 192.168.100.1
Tue Dec  8 17:36:29 2015 daemon.notice pppd[4842]: primary   DNS address 85.37.17.17
Tue Dec  8 17:36:29 2015 daemon.notice pppd[4842]: secondary DNS address 85.38.28.72
Tue Dec  8 17:36:29 2015 daemon.notice pppd[4842]: local  LL address fe80::1d09:16d8:fcc8:8e50
Tue Dec  8 17:36:29 2015 daemon.notice pppd[4842]: remote LL address fe80::02a0:a50f:fc86:8004
Tue Dec  8 17:36:29 2015 daemon.info pppd[4842]: IPV6CP terminated by peer
Tue Dec  8 17:36:29 2015 daemon.notice netifd: Interface 'wan6' is enabled
Tue Dec  8 17:36:29 2015 daemon.notice netifd: Interface 'wan6' is setting up now
Tue Dec  8 17:36:29 2015 daemon.notice netifd: Interface 'wan' is now up
Tue Dec  8 17:36:29 2015 daemon.info dnsmasq[1351]: reading /tmp/resolv.conf.auto
Tue Dec  8 17:36:29 2015 daemon.info dnsmasq[1351]: using local addresses only for domain lan
Tue Dec  8 17:36:29 2015 daemon.info dnsmasq[1351]: using nameserver 85.37.17.17#53
Tue Dec  8 17:36:29 2015 daemon.info dnsmasq[1351]: using nameserver 85.38.28.72#53
Tue Dec  8 17:36:30 2015 daemon.notice netifd: Network alias 'pppoe-wan' link is up
Tue Dec  8 17:36:30 2015 daemon.notice netifd: Interface 'wan_6' is enabled
Tue Dec  8 17:36:30 2015 daemon.notice netifd: Interface 'wan_6' has link connectivity 
Tue Dec  8 17:36:30 2015 daemon.notice netifd: Interface 'wan_6' is setting up now
Tue Dec  8 17:36:30 2015 daemon.info dnsmasq[1351]: reading /tmp/resolv.conf.auto
Tue Dec  8 17:36:30 2015 daemon.info dnsmasq[1351]: using local addresses only for domain lan
Tue Dec  8 17:36:30 2015 daemon.info dnsmasq[1351]: using nameserver 85.37.17.17#53
Tue Dec  8 17:36:30 2015 daemon.info dnsmasq[1351]: using nameserver 85.38.28.72#53
Tue Dec  8 17:36:30 2015 daemon.info dnsmasq[1351]: using nameserver 85.37.17.17#53
Tue Dec  8 17:36:30 2015 daemon.info dnsmasq[1351]: using nameserver 85.38.28.72#53
Tue Dec  8 17:36:30 2015 daemon.notice netifd: Interface 'wan' has lost the connection
Tue Dec  8 17:36:30 2015 daemon.warn dnsmasq[1351]: no servers found in /tmp/resolv.conf.auto, will retry
Tue Dec  8 17:36:30 2015 daemon.notice netifd: Interface 'wan_6' is now down
Tue Dec  8 17:36:30 2015 daemon.notice netifd: Interface 'wan_6' is disabled
Tue Dec  8 17:36:30 2015 daemon.notice netifd: Interface 'wan_6' has link connectivity loss
Tue Dec  8 17:36:31 2015 user.notice firewall: Reloading firewall due to ifup of wan (pppoe-wan)
Tue Dec  8 17:36:32 2015 daemon.err insmod: module is already loaded - xt_multiport
Tue Dec  8 17:36:32 2015 daemon.err insmod: module is already loaded - xt_comment
Tue Dec  8 17:36:32 2015 daemon.err insmod: module is already loaded - xt_length
Tue Dec  8 17:36:34 2015 user.notice ddns-scripts[5139]: myddns_ipv4: PID '5139' started at 2015-12-08 17:36
Tue Dec  8 17:36:34 2015 user.warn ddns-scripts[5139]: myddns_ipv4: Service section disabled! - TERMINATE
Tue Dec  8 17:36:34 2015 user.warn ddns-scripts[5139]: myddns_ipv4: PID '5139' exit WITH ERROR '1' at 2015-12-08 17:36

pppoe authentication is ok, there is connection on the pppoe-wan interface but wan "lost connection".

Let me know how can I do...

Thanks
D.

comment:11 Changed 2 years ago by d.buffa@…

Do you think that could be usefull increase logging level of netifd? ho can I do it?

comment:12 Changed 2 years ago by nbd

Please paste your /etc/config/network

comment:13 Changed 2 years ago by d.buffa@…

This is from backup

config interface 'loopback'
	option ifname 'lo'
	option proto 'static'
	option ipaddr '127.0.0.1'
	option netmask '255.0.0.0'

config globals 'globals'
	option ula_prefix 'fd9b:264f:47a9::/48'

config interface 'lan'
	option force_link '1'
	option type 'bridge'
	option proto 'static'
	option netmask '255.255.255.0'
	option ip6assign '60'
	option ifname 'eth0.1'
	option macaddr '30:b5:c2:92:da:32'
	option ipaddr '172.20.0.1'
	option dns '8.8.8.8'
	option delegate '0'

config switch
	option name 'switch0'
	option reset '1'
	option enable_vlan '1'

config switch_vlan
	option device 'switch0'
	option vlan '1'
	option ports '0 2 4 5 6t'

config interface 'wan'
	option _orig_ifname 'ptm0'
	option _orig_bridge 'false'
	option ifname 'ptm0.835'
	option proto 'pppoe'
	option username 'aliceadsl'
	option password 'aliceadsl'
	option ipv6 'auto'

config interface 'wan6'
	option ifname '@wan'
	option proto 'dhcpv6'

config vdsl 'dsl'
	option annex 'a'
	option firmware '/lib/firmware/vdsl.bin'
	option tone 'av'
	option xfer_mode 'ptm'

config atm-bridge
	option unit '0'
	option encaps 'llc'
	option payload 'bridged'
	option vpi '8'
	option atmdev '0'
	option vci '36'

config interface 'VpnClients'
	option proto 'none'
	option ifname 'tap0'

comment:14 Changed 2 years ago by mkresin

A few more ideas from me.

Try to use an OpenWrt image with the default configuration. No zabbix, no vpn server, no backup etc. Just stock configuration + annex a firmware. Maybe you see an annoying side effect here.

Tue Dec 8 17:36:29 2015 daemon.info pppd[4842]: Remote message: REASON021 - TK MISSING: NOT IN DSL-LINE

That's a (custom) message from your provider. You might want to ask for the meaning.

Tue Dec 8 17:36:29 2015 daemon.info pppd[4842]: IPV6CP terminated by peer

Your provider doesn't support IPv6 over pppoe. Or to be more precisely, not that way.

According to http://pctutorials.altervista.org/ipv6-come-attivarlo-telecom-italiaalice/ and http://www.hwupgrade.it/forum/archive/index.php/t-2479031.html you need to use different login credentials for IPv6 support.

Usually this isn't an issue, but you either might try the IPv6 credentials or disable the request for IPv6 by changing the ipv6 option of your wan interface from auto to 0.

comment:15 follow-up: Changed 2 years ago by d.buffa@…

Hi mkresin,

I already tried with openwrt with default configuration, using self build image or published one (CC), same behaviour.

For the "REASON021 - TK MISSING: NOT IN DSL-LINE" i don't know and i don't think that my ISP would tell me something: they sell their modem and, unlike adsl, do not publish configuration information for third party vdsl modem, not because they are hostile (not only) but because they separate traffic in vlan (internet, voip and iptv) and give you a modem that simply do all the work for you, ordinary people do not need anything else...

For ipv6... i don't need it... i can disable for the wan interface, and infact one of the test i made was to remove the wan6 interface and/or disable ipv6 support... obviously if i'm here this did not solve my problem...

I have asked to my friend that work in the technical department of the ISP, and he tell me that nothing has changed in the last weeks... but i'm not sure of that, probably he simply doesn't know

The point is that if a do a ping forcing the pppoe-wan interface

root@OpenWrt:~# ping -I pppoe-wan 8.8.8.8
PING 8.8.8.8 (8.8.8.8): 56 data bytes
64 bytes from 8.8.8.8: seq=0 ttl=57 time=25.602 ms
64 bytes from 8.8.8.8: seq=1 ttl=57 time=25.614 ms
64 bytes from 8.8.8.8: seq=2 ttl=57 time=25.442 ms
64 bytes from 8.8.8.8: seq=3 ttl=57 time=25.512 ms
^C
--- 8.8.8.8 ping statistics ---
4 packets transmitted, 4 packets received, 0% packet loss
round-trip min/avg/max = 25.442/25.542/25.614 ms
root@OpenWrt:~#

I can ping external hosts, so the connection is working and pppoe authentication was ok... there is something wrong after, i don't know.... is it possible to increase logging level of netifd in order to know why interface goes down?

Thanks

comment:16 Changed 2 years ago by nbd

So here's what happens:

netifd relies on the ppp-up and ppp-down script to notify it about interface state changes. pppd first brings up ipv4 and ipv6, calls ppp-up for both.
Then your ISP terminates the ipv6 session, and pppd calls ppp-down for ipv6. This script tells netifd that the interface is down now (since it doesn't know that only one session died, not both).
So netifd considers the interface disabled from now on.
Disabling ipv6 should prevent this issue, and I'm currently thinking whether it makes sense to simply not pass ipv6 down events to netifd at all.

comment:17 Changed 2 years ago by d.buffa@…

Ok ndb,

what i have to do to disable ipv6 and be sure of it? because i already tried it, but probably I made a few mistakes...

I think i can test on friday, not before...

Thanks

P.S. This is an unofficial service request: why not make it possible to enable/disable easily ipv6 from menuconfig? it could be usefull for devices with small rom...

comment:18 in reply to: ↑ 15 Changed 2 years ago by mkresin

Replying to d.buffa@…:

For ipv6... i don't need it...

Trust me, everybody needs IPv6. You just don't know yet.

i can disable for the wan interface, and infact one of the test i made was to remove the wan6 interface and/or disable ipv6 support... obviously if i'm here this did not solve my problem...

I would guess you removed the wan6 config, but that isn't what I suggested you. Removing the wan6 interface doesn't do the job. ppp still asks for ipv6. It's just, that openwrt doesn't request the necessary informations from you ISP to deploy IPv6 to your LAN.

comment:19 Changed 2 years ago by nbd

You need to use 'option ipv6 0' in the wan section to disable ppp ipv6 support.

As for the menuconfig option to disable ipv6, that already exists under "Global build settings"

comment:20 Changed 2 years ago by d.buffa@…

Hi ndb,

you where right. After disabling ipv6 all is working again. You can close this ticket, but perhaps it might make sense to improve netifd behaviour, I don't think that i'm the only having this issue, virtually..

Thanks

Add Comment

Modify Ticket

Action
as new .
Author


E-mail address and user name can be saved in the Preferences.

 
Note: See TracTickets for help on using tickets.