1. pedro Avatar pedro
  2. FreshTomato
  3. freshtomato-arm
  4. Issues

Watchdog in Traceroute/Curl/Ping mode doesn't restart wan connection in case of internet connection loss

Issue #8 invalid
dworniok created an issue

2. The biggest image available for below routers 2020.1 (AIO)

3. Asus RT-AC56U, RT-N18U

4. AC56U Total / Free NVRAM 64.00 KB / 20.41 KB (31.90%); RT-N18U Total / Free NVRAM 64.00 KB / 26.18 KB (40.90%)

5.

6. Issue is valid for many many FT versions back. I check it like this:

6.1. I have Huawei B528 in brigde mode (but was valid for E3272 too).

6.2. Disconnect LTE in Huawei B528

6.3. After few minutes, WAN connection is still not restarted.

‌

I’m not sure but issue occurs in PING mode too - currently I’m using CURL and this is working almost fine (in case of high load of internet connection, sometimes CURL restarts the connection).

Issue was valid for MIPS versions - but I don't have access right now to provide additional informations.

Comments (20)

  2. dworniok reporter

    Today issue occured with Curl also.

    Logs full of vpn, dns and adblock witch trying connect with the internet.

    ‌

  5. Not Sure

    @dworniok connect ssh and write this command: 

    nvram set mwan_debug=8
nvram commit

    This will turn on very detailed debugging for multiwan feature. Make the issue appear then upload the log here.

    Later on you can switch it back off using:

    nvram set mwan_debug=0
nvram commit

    ‌

  6. dworniok reporter

    After fresh start, at 17:27 I disconnected LTE connection. Watchdog in Traceroute mode with 1 minute interval.

    Jan  1 01:00:05 XXXXXXXXX syslog.info syslogd started: BusyBox v1.25.1
Jan  1 01:00:05 XXXXXXXXX kern.notice kernel: klogd started: BusyBox v1.25.1 (2020-01-20 01:26:47 CET)
Jan  1 01:00:05 XXXXXXXXX kern.notice kernel: Linux version 2.6.36.4brcmarm (pedro@debian9) (gcc version 4.5.3 (Buildroot 2012.02) ) #20 SMP PREEMPT Mon Jan 20 02:04:21 CET 2020
Jan  1 01:00:05 XXXXXXXXX kern.warn kernel: CPU: ARMv7 Processor [413fc090] revision 0 (ARMv7), cr=10c53c7f
Jan  1 01:00:05 XXXXXXXXX kern.warn kernel: CPU: VIPT nonaliasing data cache, VIPT nonaliasing instruction cache
Jan  1 01:00:05 XXXXXXXXX kern.warn kernel: Machine: Northstar Prototype
Jan  1 01:00:05 XXXXXXXXX kern.warn kernel: Ignoring unrecognised tag 0x00000000
Jan  1 01:00:05 XXXXXXXXX kern.info kernel: bootconsole [earlycon0] enabled
Jan  1 01:00:05 XXXXXXXXX kern.warn kernel: Memory policy: ECC disabled, Data cache writealloc
Jan  1 01:00:05 XXXXXXXXX kern.info kernel: MPCORE found at 19020000
Jan  1 01:00:05 XXXXXXXXX kern.debug kernel: On node 0 totalpages: 65536
Jan  1 01:00:05 XXXXXXXXX kern.debug kernel:   DMA zone: 256 pages used for memmap
Jan  1 01:00:05 XXXXXXXXX kern.debug kernel:   DMA zone: 0 pages reserved
Jan  1 01:00:05 XXXXXXXXX kern.debug kernel:   DMA zone: 32512 pages, LIFO batch:7
Jan  1 01:00:05 XXXXXXXXX kern.debug kernel:   Normal zone: 4864 pages used for memmap
Jan  1 01:00:05 XXXXXXXXX kern.debug kernel:   Normal zone: 27904 pages, LIFO batch:7
Jan  1 01:00:05 XXXXXXXXX kern.info kernel: PERCPU: Embedded 7 pages/cpu @c8215000 s5504 r8192 d14976 u65536
Jan  1 01:00:05 XXXXXXXXX kern.info kernel: pcpu-alloc: s5504 r8192 d14976 u65536 alloc=16*4096
Jan  1 01:00:05 XXXXXXXXX kern.info kernel: pcpu-alloc: [0] 0 [0] 1 
Jan  1 01:00:05 XXXXXXXXX kern.warn kernel: Built 1 zonelists in Zone order, mobility grouping on.  Total pages: 60416
Jan  1 01:00:05 XXXXXXXXX kern.notice kernel: Kernel command line: root=/dev/mtdblock2 console=ttyS0,115200 init=/sbin/preinit earlyprintk debug
Jan  1 01:00:05 XXXXXXXXX kern.info kernel: PID hash table entries: 1024 (order: 0, 4096 bytes)
Jan  1 01:00:05 XXXXXXXXX kern.info kernel: Dentry cache hash table entries: 32768 (order: 5, 131072 bytes)
Jan  1 01:00:05 XXXXXXXXX kern.info kernel: Inode-cache hash table entries: 16384 (order: 4, 65536 bytes)
Jan  1 01:00:05 XXXXXXXXX kern.info kernel: Memory: 128MB 128MB = 256MB total
Jan  1 01:00:05 XXXXXXXXX kern.notice kernel: Memory: 255400k/255400k available, 6744k reserved, 0K highmem
Jan  1 01:00:05 XXXXXXXXX kern.notice kernel: Virtual kernel memory layout:
Jan  1 01:00:05 XXXXXXXXX kern.notice kernel:     vector  : 0xffff0000 - 0xffff1000   (   4 kB)
Jan  1 01:00:05 XXXXXXXXX kern.notice kernel:     fixmap  : 0xfff00000 - 0xfffe0000   ( 896 kB)
Jan  1 01:00:05 XXXXXXXXX kern.notice kernel:     DMA     : 0xf7e00000 - 0xffe00000   ( 128 MB)
Jan  1 01:00:05 XXXXXXXXX kern.notice kernel:     vmalloc : 0xd0800000 - 0xf0000000   ( 504 MB)
Jan  1 01:00:05 XXXXXXXXX kern.notice kernel:     lowmem  : 0xc0000000 - 0xd0000000   ( 256 MB)
Jan  1 01:00:05 XXXXXXXXX kern.notice kernel:     modules : 0xbf000000 - 0xc0000000   (  16 MB)
Jan  1 01:00:05 XXXXXXXXX kern.notice kernel:       .init : 0xc0008000 - 0xc003e000   ( 216 kB)
Jan  1 01:00:05 XXXXXXXXX kern.notice kernel:       .text : 0xc003e000 - 0xc03c3000   (3604 kB)
Jan  1 01:00:05 XXXXXXXXX kern.notice kernel:       .data : 0xc03dc000 - 0xc03fff00   ( 144 kB)
Jan  1 01:00:05 XXXXXXXXX kern.info kernel: SLUB: Genslabs=11, HWalign=32, Order=0-3, MinObjects=0, CPUs=2, Nodes=1
Jan  1 01:00:05 XXXXXXXXX kern.info kernel: Hierarchical RCU implementation.
Jan  1 01:00:05 XXXXXXXXX kern.info kernel:     RCU-based detection of stalled CPUs is disabled.
Jan  1 01:00:05 XXXXXXXXX kern.info kernel:     Verbose stalled-CPUs detection is disabled.
Jan  1 01:00:05 XXXXXXXXX kern.info kernel: NR_IRQS:256
Jan  1 01:00:05 XXXXXXXXX kern.info kernel: MPCORE GIC init
Jan  1 01:00:05 XXXXXXXXX kern.warn kernel: External imprecise Data abort at addr=0x0, fsr=0x1c06 ignored.
Jan  1 01:00:05 XXXXXXXXX kern.info kernel: MPCORE Global Timer Clock 400000000Hz
Jan  1 01:00:05 XXXXXXXXX kern.info kernel: Calibrating delay loop... 1599.07 BogoMIPS (lpj=7995392)
Jan  1 01:00:05 XXXXXXXXX kern.info kernel: pid_max: default: 32768 minimum: 301
Jan  1 01:00:05 XXXXXXXXX kern.warn kernel: Mount-cache hash table entries: 512
Jan  1 01:00:05 XXXXXXXXX kern.info kernel: CPU: Testing write buffer coherency: ok
Jan  1 01:00:05 XXXXXXXXX kern.info kernel: MPCORE Private timer setup CPU0
Jan  1 01:00:05 XXXXXXXXX kern.info kernel: Calibrating local timer... 399.800MHz.
Jan  1 01:00:05 XXXXXXXXX kern.info kernel: L310: cache controller enabled 16 ways, CACHE_ID 0x410000c8, AUX_CTRL 0x7a130000
Jan  1 01:00:05 XXXXXXXXX kern.warn kernel: CPU1: Booted secondary processor
Jan  1 01:00:05 XXXXXXXXX kern.info kernel: MPCORE Private timer setup CPU1
Jan  1 01:00:05 XXXXXXXXX kern.info kernel: Brought up 2 CPUs
Jan  1 01:00:05 XXXXXXXXX kern.info kernel: SMP: Total of 2 processors activated (3194.88 BogoMIPS).
Jan  1 01:00:05 XXXXXXXXX kern.info kernel: devtmpfs: initialized
Jan  1 01:00:05 XXXXXXXXX kern.info kernel: atomic64 test passed
Jan  1 01:00:05 XXXXXXXXX kern.info kernel: NET: Registered protocol family 16
Jan  1 01:00:05 XXXXXXXXX kern.warn kernel: Found a AMD NAND flash:
Jan  1 01:00:05 XXXXXXXXX kern.warn kernel: Total size:  128MB
Jan  1 01:00:05 XXXXXXXXX kern.warn kernel: Block size:  128KB
Jan  1 01:00:05 XXXXXXXXX kern.warn kernel: Page Size:   2048B
Jan  1 01:00:05 XXXXXXXXX kern.warn kernel: OOB Size:    64B
Jan  1 01:00:05 XXXXXXXXX kern.warn kernel: Sector size: 512B
Jan  1 01:00:05 XXXXXXXXX kern.warn kernel: Spare size:  16B
Jan  1 01:00:05 XXXXXXXXX kern.warn kernel: ECC level:   8 (8-bit)
Jan  1 01:00:05 XXXXXXXXX kern.warn kernel: Device ID: 0x 1 0xf1 0x 0 0x1d 0x 1 0xf1
Jan  1 01:00:05 XXXXXXXXX kern.info kernel: CCA UART Clock Config: Sel=1 Ovr=1 Div=48
Jan  1 01:00:05 XXXXXXXXX kern.info kernel: CCA UART Clock rate 100000000Hz
Jan  1 01:00:05 XXXXXXXXX kern.warn kernel: bio: create slab <bio-0> at 0
Jan  1 01:00:05 XXXXXXXXX kern.info kernel: Switching to clocksource mpcore_gtimer
Jan  1 01:00:05 XXXXXXXXX kern.info kernel: NET: Registered protocol family 2
Jan  1 01:00:05 XXXXXXXXX kern.info kernel: IP route cache hash table entries: 2048 (order: 1, 8192 bytes)
Jan  1 01:00:05 XXXXXXXXX kern.info kernel: TCP established hash table entries: 8192 (order: 4, 65536 bytes)
Jan  1 01:00:06 XXXXXXXXX kern.info kernel: TCP bind hash table entries: 8192 (order: 4, 98304 bytes)
Jan  1 01:00:06 XXXXXXXXX kern.info kernel: TCP: Hash tables configured (established 8192 bind 8192)
Jan  1 01:00:06 XXXXXXXXX kern.info kernel: TCP reno registered
Jan  1 01:00:06 XXXXXXXXX kern.info kernel: UDP hash table entries: 128 (order: 0, 4096 bytes)
Jan  1 01:00:06 XXXXXXXXX kern.info kernel: UDP-Lite hash table entries: 128 (order: 0, 4096 bytes)
Jan  1 01:00:06 XXXXXXXXX kern.info kernel: NET: Registered protocol family 1
Jan  1 01:00:06 XXXXXXXXX kern.debug kernel: PCI: CLS 0 bytes, default 32
Jan  1 01:00:06 XXXXXXXXX kern.warn kernel: PCI: no core
Jan  1 01:00:06 XXXXXXXXX kern.warn kernel: PCI: no core
Jan  1 01:00:06 XXXXXXXXX kern.info kernel: PCI: scanning bus 0
Jan  1 01:00:06 XXXXXXXXX kern.debug kernel: pci 0000:00:00.0: reg 10: [mem 0x18000000-0x18000fff]
Jan  1 01:00:06 XXXXXXXXX kern.debug kernel: pci 0000:00:00.0: reg 30: [mem 0x00000000-0x000007ff pref]
Jan  1 01:00:06 XXXXXXXXX kern.debug kernel: pci 0000:00:01.0: reg 10: [mem 0x18001000-0x18001fff]
Jan  1 01:00:06 XXXXXXXXX kern.debug kernel: pci 0000:00:01.0: reg 14: [mem 0x18002000-0x18002fff]
Jan  1 01:00:06 XXXXXXXXX kern.debug kernel: pci 0000:00:01.0: reg 30: [mem 0x00000000-0x000007ff pref]
Jan  1 01:00:06 XXXXXXXXX kern.debug kernel: pci 0000:00:02.0: reg 10: [mem 0x1802c000-0x1802cfff]
Jan  1 01:00:06 XXXXXXXXX kern.debug kernel: pci 0000:00:02.0: reg 30: [mem 0x00000000-0x000007ff pref]
Jan  1 01:00:06 XXXXXXXXX kern.debug kernel: pci 0000:00:03.0: reg 10: [mem 0x18024000-0x18024fff]
Jan  1 01:00:06 XXXXXXXXX kern.debug kernel: pci 0000:00:03.0: reg 30: [mem 0x00000000-0x000007ff pref]
Jan  1 01:00:06 XXXXXXXXX kern.debug kernel: pci 0000:00:04.0: reg 10: [mem 0x18025000-0x18025fff]
Jan  1 01:00:06 XXXXXXXXX kern.debug kernel: pci 0000:00:04.0: reg 30: [mem 0x00000000-0x000007ff pref]
Jan  1 01:00:06 XXXXXXXXX kern.debug kernel: pci 0000:00:05.0: reg 10: [mem 0x18026000-0x18026fff]
Jan  1 01:00:06 XXXXXXXXX kern.debug kernel: pci 0000:00:05.0: reg 30: [mem 0x00000000-0x000007ff pref]
Jan  1 01:00:06 XXXXXXXXX kern.debug kernel: pci 0000:00:06.0: reg 10: [mem 0x18027000-0x18027fff]
Jan  1 01:00:06 XXXXXXXXX kern.debug kernel: pci 0000:00:06.0: reg 30: [mem 0x00000000-0x000007ff pref]
Jan  1 01:00:06 XXXXXXXXX kern.debug kernel: pci 0000:00:07.0: reg 10: [mem 0x18012000-0x18012fff]
Jan  1 01:00:06 XXXXXXXXX kern.debug kernel: pci 0000:00:07.0: reg 30: [mem 0x00000000-0x000007ff pref]
Jan  1 01:00:06 XXXXXXXXX kern.debug kernel: pci 0000:00:08.0: reg 10: [mem 0x18013000-0x18013fff]
Jan  1 01:00:06 XXXXXXXXX kern.debug kernel: pci 0000:00:08.0: reg 30: [mem 0x00000000-0x000007ff pref]
Jan  1 01:00:06 XXXXXXXXX kern.debug kernel: pci 0000:00:09.0: reg 10: [mem 0x18014000-0x18014fff]
Jan  1 01:00:06 XXXXXXXXX kern.debug kernel: pci 0000:00:09.0: reg 30: [mem 0x00000000-0x000007ff pref]
Jan  1 01:00:06 XXXXXXXXX kern.debug kernel: pci 0000:00:0a.0: reg 10: [mem 0x1800b000-0x1800bfff]
Jan  1 01:00:06 XXXXXXXXX kern.debug kernel: pci 0000:00:0a.0: reg 14: [mem 0x1800c000-0x1800cfff]
Jan  1 01:00:06 XXXXXXXXX kern.debug kernel: pci 0000:00:0a.0: reg 30: [mem 0x00000000-0x000007ff pref]
Jan  1 01:00:06 XXXXXXXXX kern.debug kernel: pci 0000:00:0b.0: reg 10: [mem 0x18022000-0x18022fff]
Jan  1 01:00:06 XXXXXXXXX kern.debug kernel: pci 0000:00:0b.0: reg 30: [mem 0x00000000-0x000007ff pref]
Jan  1 01:00:06 XXXXXXXXX kern.debug kernel: pci 0000:00:0b.1: reg 10: [mem 0x18021000-0x18021fff]
Jan  1 01:00:06 XXXXXXXXX kern.debug kernel: pci 0000:00:0b.1: reg 30: [mem 0x00000000-0x000007ff pref]
Jan  1 01:00:06 XXXXXXXXX kern.debug kernel: pci 0000:00:0c.0: reg 10: [mem 0x18023000-0x18023fff]
Jan  1 01:00:06 XXXXXXXXX kern.debug kernel: pci 0000:00:0c.0: reg 30: [mem 0x00000000-0x000007ff pref]
Jan  1 01:00:06 XXXXXXXXX kern.debug kernel: pci 0000:00:0d.0: reg 10: [mem 0x18020000-0x18020fff]
Jan  1 01:00:06 XXXXXXXXX kern.debug kernel: pci 0000:00:0d.0: reg 30: [mem 0x00000000-0x000007ff pref]
Jan  1 01:00:06 XXXXXXXXX kern.debug kernel: pci 0000:00:0e.0: reg 10: [mem 0x18210000-0x1821ffff]
Jan  1 01:00:06 XXXXXXXXX kern.debug kernel: pci 0000:00:0e.0: reg 30: [mem 0x00000000-0x000007ff pref]
Jan  1 01:00:06 XXXXXXXXX kern.debug kernel: pci 0000:00:0f.0: reg 10: [mem 0x18010000-0x18010fff]
Jan  1 01:00:06 XXXXXXXXX kern.debug kernel: pci 0000:00:0f.0: reg 30: [mem 0x00000000-0x000007ff pref]
Jan  1 01:00:06 XXXXXXXXX kern.debug kernel: pci 0000:00:10.0: reg 30: [mem 0x00000000-0x000007ff pref]
Jan  1 01:00:06 XXXXXXXXX kern.debug kernel: pci 0000:00:11.0: reg 10: [mem 0x18028000-0x18028fff]
Jan  1 01:00:06 XXXXXXXXX kern.debug kernel: pci 0000:00:11.0: reg 30: [mem 0x00000000-0x000007ff pref]
Jan  1 01:00:06 XXXXXXXXX kern.debug kernel: pci 0000:00:12.0: reg 10: [mem 0x18029000-0x18029fff]
Jan  1 01:00:06 XXXXXXXXX kern.debug kernel: pci 0000:00:12.0: reg 30: [mem 0x00000000-0x000007ff pref]
Jan  1 01:00:06 XXXXXXXXX kern.warn kernel: PCI: Fixing up bus 0
Jan  1 01:00:06 XXXXXXXXX kern.info kernel: PCIE1 link=1
Jan  1 01:00:06 XXXXXXXXX kern.info kernel: PCIE1 switching to GEN2
Jan  1 01:00:06 XXXXXXXXX kern.info kernel: PCIE1 link=1
Jan  1 01:00:06 XXXXXXXXX kern.debug kernel: pci 0001:00:00.0: PME# supported from D0 D3hot D3cold
Jan  1 01:00:06 XXXXXXXXX kern.debug kernel: pci 0001:00:00.0: PME# disabled
Jan  1 01:00:06 XXXXXXXXX kern.warn kernel: PCI: Fixing up bus 0
Jan  1 01:00:06 XXXXXXXXX kern.info kernel: PCI: bus0: Fast back to back transfers disabled
Jan  1 01:00:06 XXXXXXXXX kern.debug kernel: pci 0001:01:00.0: reg 10: [mem 0x00000000-0x00007fff 64bit]
Jan  1 01:00:06 XXXXXXXXX kern.debug kernel: pci 0001:01:00.0: supports D1 D2
Jan  1 01:00:06 XXXXXXXXX kern.warn kernel: PCI: Fixing up bus 1
Jan  1 01:00:06 XXXXXXXXX kern.info kernel: PCI: bus1: Fast back to back transfers disabled
Jan  1 01:00:06 XXXXXXXXX kern.info kernel: pci 0001:00:00.0: BAR 8: assigned [mem 0x08000000-0x080fffff]
Jan  1 01:00:06 XXXXXXXXX kern.info kernel: pci 0001:01:00.0: BAR 0: assigned [mem 0x08000000-0x08007fff 64bit]
Jan  1 01:00:06 XXXXXXXXX kern.info kernel: pci 0001:01:00.0: BAR 0: set to [mem 0x08000000-0x08007fff 64bit] (PCI address [0x8000000-0x8007fff]
Jan  1 01:00:06 XXXXXXXXX kern.info kernel: pci 0001:00:00.0: PCI bridge to [bus 01-01]
Jan  1 01:00:06 XXXXXXXXX kern.info kernel: pci 0001:00:00.0:   bridge window [io  disabled]
Jan  1 01:00:06 XXXXXXXXX kern.info kernel: pci 0001:00:00.0:   bridge window [mem 0x08000000-0x080fffff]
Jan  1 01:00:06 XXXXXXXXX kern.info kernel: pci 0001:00:00.0:   bridge window [mem pref disabled]
Jan  1 01:00:06 XXXXXXXXX kern.info kernel: PCIE2 link=1
Jan  1 01:00:06 XXXXXXXXX kern.info kernel: PCIE2 switching to GEN2
Jan  1 01:00:06 XXXXXXXXX kern.info kernel: PCIE2 link=1
Jan  1 01:00:06 XXXXXXXXX kern.debug kernel: pci 0002:00:00.0: PME# supported from D0 D3hot D3cold
Jan  1 01:00:06 XXXXXXXXX kern.debug kernel: pci 0002:00:00.0: PME# disabled
Jan  1 01:00:06 XXXXXXXXX kern.warn kernel: PCI: Fixing up bus 0
Jan  1 01:00:06 XXXXXXXXX kern.info kernel: PCI: bus0: Fast back to back transfers disabled
Jan  1 01:00:06 XXXXXXXXX kern.debug kernel: pci 0002:02:00.0: reg 10: [mem 0x40000000-0x40007fff 64bit]
Jan  1 01:00:06 XXXXXXXXX kern.debug kernel: pci 0002:02:00.0: supports D1 D2
Jan  1 01:00:06 XXXXXXXXX kern.warn kernel: PCI: Fixing up bus 2
Jan  1 01:00:06 XXXXXXXXX user.debug hotplug[805]: Attached USB device 1-0:1.0 [INTERFACE=9/0/0 PRODUCT=1d6b/3/206]
Jan  1 01:00:06 XXXXXXXXX kern.info kernel: PCI: bus2: Fast back to back transfers disabled
Jan  1 01:00:06 XXXXXXXXX kern.info kernel: pci 0002:00:00.0: BAR 8: assigned [mem 0x40000000-0x400fffff]
Jan  1 01:00:06 XXXXXXXXX kern.info kernel: pci 0002:02:00.0: BAR 0: assigned [mem 0x40000000-0x40007fff 64bit]
Jan  1 01:00:06 XXXXXXXXX kern.info kernel: pci 0002:02:00.0: BAR 0: set to [mem 0x40000000-0x40007fff 64bit] (PCI address [0x40000000-0x40007fff]
Jan  1 01:00:06 XXXXXXXXX kern.info kernel: pci 0002:00:00.0: PCI bridge to [bus 02-02]
Jan  1 01:00:06 XXXXXXXXX kern.info kernel: pci 0002:00:00.0:   bridge window [io  disabled]
Jan  1 01:00:06 XXXXXXXXX kern.info kernel: pci 0002:00:00.0:   bridge window [mem 0x40000000-0x400fffff]
Jan  1 01:00:06 XXXXXXXXX kern.info kernel: pci 0002:00:00.0:   bridge window [mem pref disabled]
Jan  1 01:00:06 XXXXXXXXX kern.info kernel: PCIE3 link=0
Jan  1 01:00:06 XXXXXXXXX kern.notice kernel: VFS: Disk quotas dquot_6.5.2
Jan  1 01:00:06 XXXXXXXXX kern.warn kernel: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
Jan  1 01:00:06 XXXXXXXXX kern.info kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher
Jan  1 01:00:06 XXXXXXXXX kern.info kernel: fuse init (API version 7.15)
Jan  1 01:00:06 XXXXXXXXX kern.info kernel: msgmni has been set to 498
Jan  1 01:00:06 XXXXXXXXX kern.info kernel: io scheduler noop registered (default)
Jan  1 01:00:06 XXXXXXXXX kern.info kernel: io scheduler deadline registered
Jan  1 01:00:06 XXXXXXXXX kern.info kernel: io scheduler cfq registered
Jan  1 01:00:06 XXXXXXXXX kern.info kernel: Serial: 8250/16550 driver, 2 ports, IRQ sharing disabled
Jan  1 01:00:06 XXXXXXXXX kern.info kernel: serial8250.0: ttyS0 at MMIO 0x18000300 (irq = 117) is a 16550
Jan  1 01:00:06 XXXXXXXXX kern.info kernel: console [ttyS0] enabled, bootconsole disabled
Jan  1 01:00:06 XXXXXXXXX kern.info kernel: serial8250.0: ttyS1 at MMIO 0x18000400 (irq = 117) is a 16550
Jan  1 01:00:06 XXXXXXXXX kern.info kernel: brd: module loaded
Jan  1 01:00:06 XXXXXXXXX kern.info kernel: loop: module loaded
Jan  1 01:00:06 XXXXXXXXX kern.err kernel: pflash: found no supported devices
Jan  1 01:00:06 XXXXXXXXX kern.err kernel: bcmsflash: found no supported devices
Jan  1 01:00:06 XXXXXXXXX kern.warn kernel: Boot partition size = 524288(0x80000)
Jan  1 01:00:06 XXXXXXXXX kern.warn kernel: lookup_nflash_rootfs_offset: offset = 0x200000
Jan  1 01:00:06 XXXXXXXXX kern.notice kernel: nflash: squash filesystem with lzma found at block 29
Jan  1 01:00:06 XXXXXXXXX kern.notice kernel: Creating 4 MTD partitions on "nflash":
Jan  1 01:00:06 XXXXXXXXX kern.notice kernel: 0x000000000000-0x000000080000 : "boot"
Jan  1 01:00:06 XXXXXXXXX kern.notice kernel: 0x000000080000-0x000000200000 : "nvram"
Jan  1 01:00:06 XXXXXXXXX kern.notice kernel: 0x000000200000-0x000002000000 : "linux"
Jan  1 01:00:06 XXXXXXXXX kern.notice kernel: 0x0000003a8060-0x000002000000 : "rootfs"
Jan  1 01:00:06 XXXXXXXXX kern.info kernel: PPP generic driver version 2.4.2
Jan  1 01:00:06 XXXXXXXXX kern.info kernel: PPP MPPE Compression module registered
Jan  1 01:00:06 XXXXXXXXX kern.info kernel: NET: Registered protocol family 24
Jan  1 01:00:06 XXXXXXXXX kern.info kernel: PPTP driver version 0.8.5
Jan  1 01:00:06 XXXXXXXXX kern.warn kernel: === PPTP init ===
Jan  1 01:00:06 XXXXXXXXX kern.info kernel: Mirror/redirect action on
Jan  1 01:00:06 XXXXXXXXX kern.info kernel: u32 classifier
Jan  1 01:00:06 XXXXXXXXX kern.info kernel:     Actions configured
Jan  1 01:00:06 XXXXXXXXX kern.info kernel: Netfilter messages via NETLINK v0.30.
Jan  1 01:00:06 XXXXXXXXX kern.info kernel: nf_conntrack version 0.5.0 (3990 buckets, 15960 max)
Jan  1 01:00:06 XXXXXXXXX kern.info kernel: NF_TPROXY: Transparent proxy support initialized, version 4.1.0
Jan  1 01:00:06 XXXXXXXXX kern.info kernel: NF_TPROXY: Copyright (c) 2006-2007 BalaBit IT Ltd.
Jan  1 01:00:06 XXXXXXXXX kern.info kernel: xt_time: kernel timezone is -0000
Jan  1 01:00:06 XXXXXXXXX kern.info kernel: ip_tables: (C) 2000-2006 Netfilter Core Team
Jan  1 01:00:06 XXXXXXXXX kern.info kernel: TCP cubic registered
Jan  1 01:00:06 XXXXXXXXX kern.info kernel: NET: Registered protocol family 10
Jan  1 01:00:06 XXXXXXXXX kern.info kernel: ip6_tables: (C) 2000-2006 Netfilter Core Team
Jan  1 01:00:06 XXXXXXXXX kern.info kernel: NET: Registered protocol family 17
Jan  1 01:00:06 XXXXXXXXX kern.info kernel: L2TP core driver, V2.0
Jan  1 01:00:06 XXXXXXXXX kern.info kernel: PPPoL2TP kernel driver, V2.0
Jan  1 01:00:06 XXXXXXXXX kern.info kernel: 802.1Q VLAN Support v1.8 Ben Greear <greearb@candelatech.com>
Jan  1 01:00:06 XXXXXXXXX kern.info kernel: All bugs added by David S. Miller <davem@redhat.com>
Jan  1 01:00:06 XXXXXXXXX kern.notice kernel: Registering the dns_resolver key type
Jan  1 01:00:06 XXXXXXXXX kern.info kernel: Northstar brcmnand NAND Flash Controller driver, Version 0.1 (c) Broadcom Inc. 2012
Jan  1 01:00:06 XXXXXXXXX kern.info kernel: NAND device: Manufacturer ID: 0x01, Chip ID: 0xf1 (AMD NAND 128MiB 3,3V 8-bit)
Jan  1 01:00:06 XXXXXXXXX kern.warn kernel: Spare area=64 eccbytes 56, ecc bytes located at:
Jan  1 01:00:06 XXXXXXXXX kern.warn kernel:  2 3 4 5 6 7 8 9 10 11 12 13 14 15 18 19 20 21 22 23 24 25 26 27 28 29 30 31 34 35 36 37 38 39 40 41 42 43 44 45 46 47 50 51 52 53 54 55 56 57 58 59 60 61 62 63
Jan  1 01:00:06 XXXXXXXXX kern.warn kernel: Available 7 bytes at (off,len):
Jan  1 01:00:06 XXXXXXXXX kern.warn kernel: (1,1) (16,2) (32,2) (48,2) (0,0) (0,0) (0,0) (0,0) 
Jan  1 01:00:06 XXXXXXXXX kern.info kernel: Scanning device for bad blocks
Jan  1 01:00:06 XXXXXXXXX kern.warn kernel: Options: NO_AUTOINCR,NO_READRDY,BBT_SCAN2NDPAGE,
Jan  1 01:00:06 XXXXXXXXX kern.notice kernel: Creating 2 MTD partitions on "brcmnand":
Jan  1 01:00:06 XXXXXXXXX kern.notice kernel: 0x000002000000-0x000008000000 : "brcmnand"
Jan  1 01:00:06 XXXXXXXXX kern.notice kernel: 0x000004000000-0x000008000000 : "jffs2"
Jan  1 01:00:06 XXXXXXXXX kern.warn kernel: VFS: Mounted root (squashfs filesystem) readonly on device 31:3.
Jan  1 01:00:06 XXXXXXXXX kern.info kernel: devtmpfs: mounted
Jan  1 01:00:06 XXXXXXXXX kern.info kernel: Freeing init memory: 216K
Jan  1 01:00:06 XXXXXXXXX kern.warn kernel: ctf: module license 'Proprietary' taints kernel.
Jan  1 01:00:06 XXXXXXXXX kern.warn kernel: Disabling lock debugging due to kernel taint
Jan  1 01:00:06 XXXXXXXXX kern.warn kernel: et_module_init: passivemode set to 0x0
Jan  1 01:00:06 XXXXXXXXX kern.warn kernel: et_module_init: txworkq set to 0x1
Jan  1 01:00:06 XXXXXXXXX kern.warn kernel: et_module_init: et_txq_thresh set to 0x0
Jan  1 01:00:06 XXXXXXXXX kern.warn kernel: eth0: Broadcom BCM47XX 10/100/1000 Mbps Ethernet Controller 6.37.14.86 (r456083)
Jan  1 01:00:06 XXXXXXXXX kern.warn kernel: wl_module_init: passivemode set to 0x0
Jan  1 01:00:06 XXXXXXXXX kern.warn kernel: wl_module_init: txworkq set to 0x1
Jan  1 01:00:06 XXXXXXXXX kern.warn kernel: PCI: Enabling device 0001:01:00.0 (0140 -> 0142)
Jan  1 01:00:06 XXXXXXXXX kern.warn kernel: eth1: Broadcom BCMa8db 802.11 Wireless Controller 6.37.14.86 (r456083)
Jan  1 01:00:07 XXXXXXXXX kern.warn kernel: eth2: Broadcom BCM4360 802.11 Wireless Controller 6.37.14.86 (r456083)
Jan  1 01:00:07 XXXXXXXXX kern.warn kernel: nf_conntrack_rtsp v0.7 loading
Jan  1 01:00:07 XXXXXXXXX kern.warn kernel: nf_nat_rtsp v0.7 loading
Jan  1 01:00:07 XXXXXXXXX kern.info kernel: usbcore: registered new interface driver usbfs
Jan  1 01:00:07 XXXXXXXXX kern.info kernel: usbcore: registered new interface driver hub
Jan  1 01:00:07 XXXXXXXXX kern.info kernel: usbcore: registered new device driver usb
Jan  1 01:00:07 XXXXXXXXX kern.notice kernel: SCSI subsystem initialized
Jan  1 01:00:07 XXXXXXXXX kern.warn kernel: csw_retry 100
Jan  1 01:00:07 XXXXXXXXX kern.info kernel: Initializing USB Mass Storage driver...
Jan  1 01:00:07 XXXXXXXXX kern.info kernel: usbcore: registered new interface driver usb-storage
Jan  1 01:00:07 XXXXXXXXX kern.info kernel: USB Mass Storage support registered.
Jan  1 01:00:07 XXXXXXXXX kern.info kernel: xhci_hcd 0000:00:0c.0: xHCI Host Controller
Jan  1 01:00:07 XXXXXXXXX kern.info kernel: xhci_hcd 0000:00:0c.0: new USB bus registered, assigned bus number 1
Jan  1 01:00:07 XXXXXXXXX kern.info kernel: xhci_hcd 0000:00:0c.0: irq 112, io mem 0x18023000
Jan  1 01:00:07 XXXXXXXXX kern.err kernel: xhci_hcd 0000:00:0c.0: Failed to enable MSI-X
Jan  1 01:00:07 XXXXXXXXX kern.err kernel: xhci_hcd 0000:00:0c.0: failed to allocate MSI entry
Jan  1 01:00:07 XXXXXXXXX kern.warn kernel: usb usb1: No SuperSpeed endpoint companion for config 1  interface 0 altsetting 0 ep 129: using minimum values
Jan  1 01:00:07 XXXXXXXXX kern.debug kernel: xHCI xhci_add_endpoint called for root hub
Jan  1 01:00:07 XXXXXXXXX kern.debug kernel: xHCI xhci_check_bandwidth called for root hub
Jan  1 01:00:07 XXXXXXXXX kern.info kernel: hub 1-0:1.0: USB hub found
Jan  1 01:00:07 XXXXXXXXX kern.info kernel: hub 1-0:1.0: 1 port detected
Jan  1 01:00:07 XXXXXXXXX kern.info kernel: [xhci-hub] usb2mode:[0]
Jan  1 01:00:07 XXXXXXXXX kern.info kernel: ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
Jan  1 01:00:07 XXXXXXXXX kern.info kernel: ehci_hcd 0000:00:0b.1: EHCI Host Controller
Jan  1 01:00:07 XXXXXXXXX kern.info kernel: ehci_hcd 0000:00:0b.1: new USB bus registered, assigned bus number 2
Jan  1 01:00:07 XXXXXXXXX kern.info kernel: ehci_hcd 0000:00:0b.1: irq 111, io mem 0x18021000
Jan  1 01:00:07 XXXXXXXXX kern.info kernel: ehci_hcd 0000:00:0b.1: USB 0.0 started, EHCI 1.00
Jan  1 01:00:07 XXXXXXXXX kern.info kernel: hub 2-0:1.0: USB hub found
Jan  1 01:00:07 XXXXXXXXX kern.info kernel: hub 2-0:1.0: 2 ports detected
Jan  1 01:00:07 XXXXXXXXX user.debug hotplug[814]: Attached USB device 2-0:1.0 [INTERFACE=9/0/0 PRODUCT=1d6b/2/206]
Jan  1 01:00:07 XXXXXXXXX kern.info kernel: usb 1-1: new SuperSpeed USB device using xhci_hcd and address 2
Jan  1 01:00:07 XXXXXXXXX kern.warn kernel: xhci_hcd 0000:00:0c.0: WARN: short transfer on control ep
Jan  1 01:00:07 XXXXXXXXX kern.warn kernel: xhci_hcd 0000:00:0c.0: WARN: short transfer on control ep
Jan  1 01:00:07 XXXXXXXXX user.debug hotplug[824]: Attached USB device 1-1:1.0 [INTERFACE=8/6/80 PRODUCT=174c/1153/1]
Jan  1 01:00:07 XXXXXXXXX kern.warn kernel: xhci_hcd 0000:00:0c.0: WARN: short transfer on control ep
Jan  1 01:00:07 XXXXXXXXX kern.warn kernel: xhci_hcd 0000:00:0c.0: WARN: short transfer on control ep
Jan  1 01:00:07 XXXXXXXXX kern.warn kernel: xhci_hcd 0000:00:0c.0: disable burst on ep 1
Jan  1 01:00:07 XXXXXXXXX kern.warn kernel: xhci_hcd 0000:00:0c.0: WARN no SS endpoint bMaxBurst
Jan  1 01:00:07 XXXXXXXXX kern.warn kernel: xhci_hcd 0000:00:0c.0: disable burst on ep 2
Jan  1 01:00:07 XXXXXXXXX kern.warn kernel: xhci_hcd 0000:00:0c.0: WARN no SS endpoint bMaxBurst
Jan  1 01:00:07 XXXXXXXXX kern.info kernel: scsi0 : usb-storage 1-1:1.0
Jan  1 01:00:08 XXXXXXXXX kern.info kernel: ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver
Jan  1 01:00:08 XXXXXXXXX kern.info kernel: ohci_hcd 0000:00:0b.0: OHCI Host Controller
Jan  1 01:00:08 XXXXXXXXX kern.info kernel: ohci_hcd 0000:00:0b.0: new USB bus registered, assigned bus number 3
Jan  1 01:00:08 XXXXXXXXX kern.info kernel: ohci_hcd 0000:00:0b.0: irq 111, io mem 0x18022000
Jan  1 01:00:08 XXXXXXXXX kern.info kernel: hub 3-0:1.0: USB hub found
Jan  1 01:00:08 XXXXXXXXX kern.info kernel: hub 3-0:1.0: 2 ports detected
Jan  1 01:00:08 XXXXXXXXX user.debug hotplug[836]: Attached USB device 3-0:1.0 [INTERFACE=9/0/0 PRODUCT=1d6b/1/206]
Jan  1 01:00:08 XXXXXXXXX kern.notice kernel: scsi 0:0:0:0: Direct-Access     ASMT     2115             0    PQ: 0 ANSI: 6
Jan  1 01:00:08 XXXXXXXXX kern.notice kernel: sd 0:0:0:0: [sda] 1953525168 512-byte logical blocks: (1.00 TB/931 GiB)
Jan  1 01:00:08 XXXXXXXXX kern.warn kernel: xhci_hcd 0000:00:0c.0: WARN: Stalled endpoint
Jan  1 01:00:08 XXXXXXXXX kern.notice kernel: sd 0:0:0:0: [sda] Write Protect is off
Jan  1 01:00:08 XXXXXXXXX kern.debug kernel: sd 0:0:0:0: [sda] Mode Sense: 43 00 00 00
Jan  1 01:00:08 XXXXXXXXX kern.err kernel: sd 0:0:0:0: [sda] Assuming drive cache: write through
Jan  1 01:00:08 XXXXXXXXX kern.warn kernel: xhci_hcd 0000:00:0c.0: WARN: Stalled endpoint
Jan  1 01:00:08 XXXXXXXXX kern.err kernel: sd 0:0:0:0: [sda] Assuming drive cache: write through
Jan  1 01:00:08 XXXXXXXXX kern.info kernel: usbcore: registered new interface driver usblp
Jan  1 01:00:08 XXXXXXXXX kern.info kernel:  sda: sda1 sda2
Jan  1 01:00:08 XXXXXXXXX kern.warn kernel: xhci_hcd 0000:00:0c.0: WARN: Stalled endpoint
Jan  1 01:00:08 XXXXXXXXX kern.err kernel: sd 0:0:0:0: [sda] Assuming drive cache: write through
Jan  1 01:00:08 XXXXXXXXX kern.notice kernel: sd 0:0:0:0: [sda] Attached SCSI disk
Jan  1 01:00:08 XXXXXXXXX user.info sd-idle[860]: initialized
Jan  1 01:00:09 XXXXXXXXX kern.info kernel: usb 3-2: new full speed USB device using ohci_hcd and address 2
Jan  1 01:00:09 XXXXXXXXX kern.info kernel: usblp0: USB Bidirectional printer dev 2 if 0 alt 1 proto 2 vid 0x03F0 pid 0x0E17
Jan  1 01:00:09 XXXXXXXXX user.debug hotplug[901]: Attached USB device 3-2:1.0 [INTERFACE=7/1/3 PRODUCT=3f0/e17/100]
Jan  1 01:00:09 XXXXXXXXX kern.info kernel: device vlan1 entered promiscuous mode
Jan  1 01:00:09 XXXXXXXXX kern.info kernel: device eth0 entered promiscuous mode
Jan  1 01:00:09 XXXXXXXXX kern.info kernel: device eth1 entered promiscuous mode
Jan  1 01:00:10 XXXXXXXXX kern.info kernel: device eth2 entered promiscuous mode
Jan  1 01:00:10 XXXXXXXXX user.info hotplug[850]: USB ext4 fs at /dev/sda1 mounted on /opt
Jan  1 01:00:10 XXXXXXXXX kern.info kernel: br0: port 3(eth2) entering forwarding state
Jan  1 01:00:10 XXXXXXXXX kern.info kernel: br0: port 3(eth2) entering forwarding state
Jan  1 01:00:10 XXXXXXXXX kern.info kernel: br0: port 2(eth1) entering forwarding state
Jan  1 01:00:10 XXXXXXXXX kern.info kernel: br0: port 2(eth1) entering forwarding state
Jan  1 01:00:10 XXXXXXXXX kern.info kernel: br0: port 1(vlan1) entering forwarding state
Jan  1 01:00:10 XXXXXXXXX kern.info kernel: br0: port 1(vlan1) entering forwarding state
Jan  1 01:00:10 XXXXXXXXX kern.info kernel: EXT4-fs (sda1): mounted filesystem with ordered data mode. Opts: (null)
Jan  1 01:00:10 XXXXXXXXX kern.info kernel: device wl0.1 entered promiscuous mode
Jan  1 01:00:10 XXXXXXXXX kern.info kernel: br1: port 1(wl0.1) entering forwarding state
Jan  1 01:00:10 XXXXXXXXX kern.info kernel: br1: port 1(wl0.1) entering forwarding state
Jan  1 01:00:10 XXXXXXXXX kern.info kernel: device wl1.1 entered promiscuous mode
Jan  1 01:00:10 XXXXXXXXX kern.info kernel: br2: port 1(wl1.1) entering forwarding state
Jan  1 01:00:10 XXXXXXXXX kern.info kernel: br2: port 1(wl1.1) entering forwarding state
Jan  1 01:00:10 XXXXXXXXX authpriv.info dropbear[970]: Running in background
Jan  1 01:00:11 XXXXXXXXX daemon.info dnsmasq[980]: started, version 2.80-ab53883 cachesize 4096
Jan  1 01:00:11 XXXXXXXXX daemon.info dnsmasq[980]: compile time options: IPv6 GNU-getopt no-RTC no-DBus no-UBus no-i18n no-IDN DHCP DHCPv6 no-Lua TFTP no-conntrack ipset Tomato-helper auth DNSSEC no-ID loop-detect inotify no-dumpfile
Jan  1 01:00:11 XXXXXXXXX daemon.info dnsmasq[980]: DNSSEC validation enabled
Jan  1 01:00:11 XXXXXXXXX daemon.info dnsmasq[980]: DNSSEC signature timestamps not checked until receipt of SIGINT
Jan  1 01:00:11 XXXXXXXXX daemon.info dnsmasq[980]: configured with trust anchor for <root> keytag 20326
Jan  1 01:00:11 XXXXXXXXX daemon.info dnsmasq[980]: configured with trust anchor for <root> keytag 19036
Jan  1 01:00:11 XXXXXXXXX daemon.info dnsmasq[980]: asynchronous logging enabled, queue limit is 5 messages
Jan  1 01:00:11 XXXXXXXXX daemon.info dnsmasq-dhcp[980]: DHCP, IP range 192.168.23.51 -- 192.168.23.254, lease time 1d
Jan  1 01:00:11 XXXXXXXXX daemon.info dnsmasq-dhcp[980]: DHCP, IP range 192.168.13.51 -- 192.168.13.254, lease time 1d
Jan  1 01:00:11 XXXXXXXXX daemon.info dnsmasq-dhcp[980]: DHCP, IP range 192.168.3.51 -- 192.168.3.254, lease time 1d
Jan  1 01:00:11 XXXXXXXXX daemon.info dnsmasq[980]: using nameserver 127.0.0.1#5453
Jan  1 01:00:11 XXXXXXXXX daemon.info dnsmasq[980]: read /etc/hosts - 4 addresses
Jan  1 01:00:11 XXXXXXXXX daemon.info dnsmasq[980]: read /etc/dnsmasq/hosts - 12 addresses
Jan  1 01:00:11 XXXXXXXXX daemon.info dnsmasq[980]: read /etc/dnsmasq/dhcp-hosts - 0 addresses
Jan  1 01:00:11 XXXXXXXXX daemon.info dnsmasq-dhcp[980]: read /etc/dnsmasq/hosts
Jan  1 01:00:11 XXXXXXXXX daemon.info dnsmasq-dhcp[980]: read /etc/dnsmasq/dhcp-hosts
Jan  1 01:00:13 XXXXXXXXX daemon.info dnsmasq-dhcp[980]: DHCPDISCOVER(br0) 192.168.3.3 d0:50:99:22:81:0b 
Jan  1 01:00:13 XXXXXXXXX daemon.info dnsmasq-dhcp[980]: DHCPOFFER(br0) 192.168.3.3 d0:50:99:22:81:0b 
Jan  1 01:00:13 XXXXXXXXX daemon.info dnsmasq-dhcp[980]: DHCPREQUEST(br0) 192.168.3.3 d0:50:99:22:81:0b 
Jan  1 01:00:13 XXXXXXXXX daemon.info dnsmasq-dhcp[980]: DHCPACK(br0) 192.168.3.3 d0:50:99:22:81:0b Ubuntu_Desktop
Jan  1 01:00:20 XXXXXXXXX kern.warn kernel: xhci_hcd 0000:00:0c.0: WARN: Stalled endpoint
Jan  1 01:00:20 XXXXXXXXX kern.warn kernel: xhci_hcd 0000:00:0c.0: WARN: Stalled endpoint
Jan  1 01:00:20 XXXXXXXXX kern.warn kernel: xhci_hcd 0000:00:0c.0: WARN: Stalled endpoint
Jan  1 01:00:29 XXXXXXXXX user.info hotplug[848]: USB ext4 fs at /dev/sda2 mounted on /nas
Jan  1 01:00:29 XXXXXXXXX kern.info kernel: EXT4-fs (sda2): mounted filesystem with ordered data mode. Opts: (null)
Jan  1 01:00:39 XXXXXXXXX kern.warn kernel: xhci_hcd 0000:00:0c.0: WARN: Stalled endpoint
Jan  1 01:00:39 XXXXXXXXX kern.warn kernel: xhci_hcd 0000:00:0c.0: WARN: Stalled endpoint
Jan  1 01:00:39 XXXXXXXXX kern.warn kernel: xhci_hcd 0000:00:0c.0: WARN: Stalled endpoint
Jan  1 01:00:39 XXXXXXXXX daemon.info dnsmasq-dhcp[980]: DHCPDISCOVER(br0) b8:27:eb:c7:32:d0 
Jan  1 01:00:39 XXXXXXXXX daemon.info dnsmasq-dhcp[980]: DHCPOFFER(br0) 192.168.3.2 b8:27:eb:c7:32:d0 
Jan  1 01:00:39 XXXXXXXXX daemon.info dnsmasq-dhcp[980]: DHCPREQUEST(br0) 192.168.3.2 b8:27:eb:c7:32:d0 
Jan  1 01:00:39 XXXXXXXXX daemon.info dnsmasq-dhcp[980]: DHCPACK(br0) 192.168.3.2 b8:27:eb:c7:32:d0 RaspberryPi
Jan  1 01:00:57 XXXXXXXXX user.info preinit[1]: Starting stubby 0.2.6, DNS-o-TLS Proxy
Jan  1 01:00:57 XXXXXXXXX daemon.debug stubby[1029]: Read config from file /etc/stubby.yml
Jan  1 01:00:57 XXXXXXXXX user.info preinit[1]: nginx - daemon not enabled cancelled generation of config file
Jan  1 01:00:57 XXXXXXXXX daemon.err stubby[1030]:    *FAILURE* no valid transports or upstreams available!
Jan  1 01:00:57 XXXXXXXXX daemon.err stubby[1030]:    *FAILURE* no valid transports or upstreams available!
Jan  1 01:00:58 XXXXXXXXX user.notice root: MySQL successfully stopped
Jan  1 01:00:58 XXXXXXXXX daemon.err stubby[1030]:    *FAILURE* no valid transports or upstreams available!
Jan  1 01:01:01 XXXXXXXXX daemon.err stubby[1030]:    *FAILURE* no valid transports or upstreams available!
Jan  1 01:01:01 XXXXXXXXX daemon.err stubby[1030]:    *FAILURE* no valid transports or upstreams available!
Jan  1 01:01:01 XXXXXXXXX daemon.err stubby[1030]:    *FAILURE* no valid transports or upstreams available!
Jan  1 01:01:02 XXXXXXXXX user.info preinit[1]: minidlna is stopped
Jan  1 01:01:02 XXXXXXXXX user.info preinit[1]: vsftpd is stopped
Jan  1 01:01:02 XXXXXXXXX user.info preinit[1]: samba daemon is stopped
Jan  1 01:01:02 XXXXXXXXX kern.warn kernel: gro disabled
Jan  1 01:01:03 XXXXXXXXX user.info preinit[1]: samba daemon is started
Jan  1 01:01:03 XXXXXXXXX user.notice root: Terminating transmission-daemon...
Jan  1 01:01:03 XXXXXXXXX user.notice root: Transmission daemon successfully stopped
Jan  1 01:01:03 XXXXXXXXX user.info preinit[1]: start_wan: Restarting wireless
Jan  1 01:01:04 XXXXXXXXX kern.info kernel: Ebtables v2.0 registered
Jan  1 01:01:04 XXXXXXXXX kern.info kernel: device eth1 left promiscuous mode
Jan  1 01:01:04 XXXXXXXXX kern.info kernel: br0: port 2(eth1) entering forwarding state
Jan  1 01:01:04 XXXXXXXXX kern.info kernel: device eth2 left promiscuous mode
Jan  1 01:01:04 XXXXXXXXX kern.info kernel: br0: port 3(eth2) entering forwarding state
Jan  1 01:01:04 XXXXXXXXX kern.info kernel: device wl0.1 left promiscuous mode
Jan  1 01:01:04 XXXXXXXXX kern.info kernel: br1: port 1(wl0.1) entering forwarding state
Jan  1 01:01:04 XXXXXXXXX kern.info kernel: device wl1.1 left promiscuous mode
Jan  1 01:01:04 XXXXXXXXX kern.info kernel: br2: port 1(wl1.1) entering forwarding state
Jan  1 01:01:04 XXXXXXXXX kern.info kernel: device eth1 entered promiscuous mode
Jan  1 01:01:04 XXXXXXXXX kern.info kernel: br0: port 2(eth1) entering forwarding state
Jan  1 01:01:04 XXXXXXXXX kern.info kernel: br0: port 2(eth1) entering forwarding state
Jan  1 01:01:04 XXXXXXXXX kern.info kernel: device eth2 entered promiscuous mode
Jan  1 01:01:04 XXXXXXXXX kern.info kernel: br0: port 3(eth2) entering forwarding state
Jan  1 01:01:04 XXXXXXXXX kern.info kernel: br0: port 3(eth2) entering forwarding state
Jan  1 01:01:04 XXXXXXXXX kern.info kernel: device wl0.1 entered promiscuous mode
Jan  1 01:01:04 XXXXXXXXX kern.info kernel: br1: port 1(wl0.1) entering forwarding state
Jan  1 01:01:04 XXXXXXXXX kern.info kernel: br1: port 1(wl0.1) entering forwarding state
Jan  1 01:01:04 XXXXXXXXX kern.info kernel: device wl1.1 entered promiscuous mode
Jan  1 01:01:04 XXXXXXXXX kern.info kernel: br2: port 1(wl1.1) entering forwarding state
Jan  1 01:01:04 XXXXXXXXX kern.info kernel: br2: port 1(wl1.1) entering forwarding state
Jan  1 01:01:05 XXXXXXXXX user.info preinit[1]: MultiWAN: MWAN is 1 (max 4)
Jan  1 01:01:05 XXXXXXXXX user.debug preinit[1]: MWAN: start_wan, unit=1 mode=0 prefix=wan
Jan  1 01:01:05 XXXXXXXXX user.debug preinit[1]: MWAN: IN start_wan_if (wan)
Jan  1 01:01:05 XXXXXXXXX user.debug preinit[1]: *** set_mac(vlan2,wan_mac,1) ***
Jan  1 01:01:05 XXXXXXXXX daemon.warn dnsmasq[980]: failed to access /etc/resolv.dnsmasq: No such file or directory
Jan  1 01:01:05 XXXXXXXXX user.debug preinit[1]: start_dhcpc, prefix=wan cmd = /bin/sh -c udhcpc -i vlan2 -b -s dhcpc-event -H XXXXXXXXX -O 33 -O 121 -O 249 -m    -p /var/run/udhcpc-wan.pid
Jan  1 01:01:05 XXXXXXXXX user.debug preinit[1]: MWAN: OUT start_wan_if (wan)
Jan  1 01:01:05 XXXXXXXXX user.debug dhcpc-event[1163]: dhcpc_event_main, interface=vlan2 wan_prefix=wan event=deconfig
Jan  1 01:01:05 XXXXXXXXX user.debug dhcpc-event[1188]: dhcpc_event_main, interface=vlan2 wan_prefix=wan event=bound
Jan  1 01:01:05 XXXXXXXXX user.info dhcpc-event[1188]: dhcpc_bound, interface=vlan2, wan_prefix=wan, renew=0, proto=2
Jan  1 01:01:05 XXXXXXXXX user.debug dhcpc-event[1188]: *** bound, wan_ipaddr=100.98.158.107
Jan  1 01:01:05 XXXXXXXXX user.debug dhcpc-event[1188]: *** bound, wan_netmask=255.255.255.248
Jan  1 01:01:05 XXXXXXXXX user.debug dhcpc-event[1188]: *** bound, wan_gateway=100.98.158.105
Jan  1 01:01:05 XXXXXXXXX user.debug dhcpc-event[1188]: *** bound, wan_get_dns=185.89.185.2 89.108.202.21
Jan  1 01:01:05 XXXXXXXXX user.debug dhcpc-event[1188]: *** bound, wan_routes1=
Jan  1 01:01:05 XXXXXXXXX user.debug dhcpc-event[1188]: *** bound, wan_routes2=
Jan  1 01:01:05 XXXXXXXXX user.debug dhcpc-event[1188]: *** bound, do ifconfig ...
Jan  1 01:01:05 XXXXXXXXX user.debug dhcpc-event[1188]: OUT bound: to start_wan_done, ifname=vlan2 prefix=wan ...
Jan  1 01:01:05 XXXXXXXXX user.debug dhcpc-event[1188]: MWAN: IN start_wan_done, interface=vlan2 wan_prefix=wan proto=2
Jan  1 01:01:05 XXXXXXXXX user.debug dhcpc-event[1188]: !!! start_wan_done: route_del(vlan2,0,NULL,NULL,NULL) !!! delete default interface route !!!
Jan  1 01:01:05 XXXXXXXXX user.debug dhcpc-event[1188]: !!! start_wan_done: route_add(vlan2,0,100.98.158.105,NULL,255.255.255.255) !!!
Jan  1 01:01:05 XXXXXXXXX user.debug dhcpc-event[1188]: !!! start_wan_done: route_add(vlan2,0,0.0.0.0,100.98.158.105,0.0.0.0) !!! set default gw 100.98.158.105 on vlan2 !!!
Jan  1 01:01:05 XXXXXXXXX daemon.info dnsmasq[980]: reading /etc/resolv.dnsmasq
Jan  1 01:01:05 XXXXXXXXX daemon.info dnsmasq[980]: using nameserver 127.0.0.1#5453
Jan  1 01:01:05 XXXXXXXXX daemon.info dnsmasq[980]: using nameserver 8.8.8.8#53
Jan  1 01:01:05 XXXXXXXXX daemon.info dnsmasq[980]: using nameserver 8.8.4.4#53
Jan  1 01:01:06 XXXXXXXXX user.info preinit[1]: Asus RT-AC56U: FreshTomato 2020.1 K26ARM USB AIO-64K
Jan  1 01:01:06 XXXXXXXXX user.debug mwanroute[1228]: MWAN: mwanroute launched
Jan  1 01:01:06 XXXXXXXXX user.info preinit[1]: samba daemon is started
Jan  1 01:01:06 XXXXXXXXX daemon.info dnsmasq[980]: reading /etc/resolv.dnsmasq
Jan  1 01:01:06 XXXXXXXXX daemon.info dnsmasq[980]: using nameserver 127.0.0.1#5453
Jan  1 01:01:06 XXXXXXXXX daemon.info dnsmasq[980]: using nameserver 8.8.8.8#53
Jan  1 01:01:06 XXXXXXXXX daemon.info dnsmasq[980]: using nameserver 8.8.4.4#53
Jan  1 01:01:06 XXXXXXXXX daemon.info dnsmasq[980]: exiting on receipt of SIGTERM
Jan  1 01:01:06 XXXXXXXXX daemon.info dnsmasq[1284]: started, version 2.80-ab53883 cachesize 4096
Jan  1 01:01:06 XXXXXXXXX daemon.info dnsmasq[1284]: compile time options: IPv6 GNU-getopt no-RTC no-DBus no-UBus no-i18n no-IDN DHCP DHCPv6 no-Lua TFTP no-conntrack ipset Tomato-helper auth DNSSEC no-ID loop-detect inotify no-dumpfile
Jan  1 01:01:06 XXXXXXXXX daemon.info dnsmasq[1284]: DNSSEC validation enabled
Jan  1 01:01:06 XXXXXXXXX daemon.info dnsmasq[1284]: DNSSEC signature timestamps not checked until receipt of SIGINT
Jan  1 01:01:06 XXXXXXXXX daemon.info dnsmasq[1284]: configured with trust anchor for <root> keytag 20326
Jan  1 01:01:06 XXXXXXXXX daemon.info dnsmasq[1284]: configured with trust anchor for <root> keytag 19036
Jan  1 01:01:06 XXXXXXXXX daemon.info dnsmasq[1284]: asynchronous logging enabled, queue limit is 5 messages
Jan  1 01:01:06 XXXXXXXXX daemon.info dnsmasq-dhcp[1284]: DHCP, IP range 192.168.23.51 -- 192.168.23.254, lease time 1d
Jan  1 01:01:06 XXXXXXXXX daemon.info dnsmasq-dhcp[1284]: DHCP, IP range 192.168.13.51 -- 192.168.13.254, lease time 1d
Jan  1 01:01:06 XXXXXXXXX daemon.info dnsmasq-dhcp[1284]: DHCP, IP range 192.168.3.51 -- 192.168.3.254, lease time 1d
Jan  1 01:01:06 XXXXXXXXX daemon.info dnsmasq[1284]: using nameserver 127.0.0.1#5453
Jan  1 01:01:06 XXXXXXXXX daemon.info dnsmasq[1284]: reading /etc/resolv.dnsmasq
Jan  1 01:01:06 XXXXXXXXX daemon.info dnsmasq[1284]: using nameserver 127.0.0.1#5453
Jan  1 01:01:06 XXXXXXXXX daemon.info dnsmasq[1284]: using nameserver 8.8.8.8#53
Jan  1 01:01:06 XXXXXXXXX daemon.info dnsmasq[1284]: using nameserver 8.8.4.4#53
Jan  1 01:01:06 XXXXXXXXX daemon.info dnsmasq[1284]: read /etc/hosts - 4 addresses
Jan  1 01:01:06 XXXXXXXXX daemon.info dnsmasq[1284]: read /etc/dnsmasq/hosts - 12 addresses
Jan  1 01:01:06 XXXXXXXXX daemon.info dnsmasq[1284]: read /etc/dnsmasq/dhcp-hosts - 0 addresses
Jan  1 01:01:06 XXXXXXXXX daemon.info dnsmasq-dhcp[1284]: read /etc/dnsmasq/hosts
Jan  1 01:01:06 XXXXXXXXX daemon.info dnsmasq-dhcp[1284]: read /etc/dnsmasq/dhcp-hosts
Jan  1 01:01:07 XXXXXXXXX user.info preinit[1]: Starting stubby 0.2.6, DNS-o-TLS Proxy
Jan  1 01:01:07 XXXXXXXXX daemon.debug stubby[1305]: Read config from file /etc/stubby.yml
Jan  1 01:01:08 XXXXXXXXX user.debug dhcpc-event[1188]: MWAN: do_wan_routes, interface=vlan2 metric=0 add=1 prefix=wan
Jan  1 01:01:08 XXXXXXXXX user.debug dhcpc-event[1188]: MWAN: wan is primary: true
Jan  1 01:01:09 XXXXXXXXX user.info dhcpc-event[1188]: igmpproxy is stopped
Jan  1 01:01:09 XXXXXXXXX daemon.err nmbd[1070]: *****
Jan  1 01:01:09 XXXXXXXXX daemon.err nmbd[1070]: 
Jan  1 01:01:09 XXXXXXXXX daemon.err nmbd[1070]: Samba server XXXXXXXXX is now a domain master browser for workgroup WORKGROUP on subnet UNICAST_SUBNET
Jan  1 01:01:09 XXXXXXXXX daemon.err nmbd[1070]: 
Jan  1 01:01:09 XXXXXXXXX daemon.err nmbd[1070]: *****
Jan  1 01:01:09 XXXXXXXXX daemon.err nmbd[1070]: become_domain_master_browser_bcast:
Jan  1 01:01:09 XXXXXXXXX daemon.err nmbd[1070]: Attempting to become domain master browser on workgroup WORKGROUP on subnet 192.168.3.1
Jan  1 01:01:09 XXXXXXXXX daemon.err nmbd[1070]: become_domain_master_browser_bcast: querying subnet 192.168.3.1 for domain master browser on workgroup WORKGROUP
Jan 29 17:22:19 XXXXXXXXX daemon.info dnsmasq-dhcp[1284]: DHCPDISCOVER(br1) 04:cf:8c:b4:57:56 
Jan 29 17:22:19 XXXXXXXXX daemon.info dnsmasq-dhcp[1284]: DHCPOFFER(br1) 192.168.13.3 04:cf:8c:b4:57:56 
Jan 29 17:22:19 XXXXXXXXX daemon.info dnsmasq-dhcp[1284]: DHCPREQUEST(br1) 192.168.13.3 04:cf:8c:b4:57:56 
Jan 29 17:22:19 XXXXXXXXX daemon.info dnsmasq-dhcp[1284]: DHCPACK(br1) 192.168.13.3 04:cf:8c:b4:57:56 Yeelight_02
Jan 29 17:22:21 XXXXXXXXX user.debug dhcpc-event[1188]: IN mwan_table_add
Jan 29 17:22:21 XXXXXXXXX user.debug dhcpc-event[1188]: IN mwan_table_del
Jan 29 17:22:21 XXXXXXXXX user.debug dhcpc-event[1188]: wan, cmd=ip rule del table 1 pref 101
Jan 29 17:22:21 XXXXXXXXX user.debug dhcpc-event[1188]: wan, cmd=ip rule del table 1 pref 111
Jan 29 17:22:21 XXXXXXXXX user.debug dhcpc-event[1188]: wan, cmd=ip rule del table 1 pref 111
Jan 29 17:22:21 XXXXXXXXX user.debug dhcpc-event[1188]: wan, cmd=ip rule del table 1 pref 111
Jan 29 17:22:21 XXXXXXXXX user.debug dhcpc-event[1188]: wan, cmd=ip rule del table 1 pref 121
Jan 29 17:22:21 XXXXXXXXX user.debug dhcpc-event[1188]: OUT mwan_table_del
Jan 29 17:22:21 XXXXXXXXX user.debug dhcpc-event[1188]: MWAN: OUT start_wan_done [wan]
Jan 29 17:22:21 XXXXXXXXX user.debug dhcpc-event[1678]: dhcpc_event_main, interface=vlan2 wan_prefix=wan event=deconfig
Jan 29 17:22:22 XXXXXXXXX user.debug dhcpc-event[1679]: dhcpc_event_main, interface=vlan2 wan_prefix=wan event=bound
Jan 29 17:22:22 XXXXXXXXX user.info dhcpc-event[1679]: dhcpc_bound, interface=vlan2, wan_prefix=wan, renew=0, proto=2
Jan 29 17:22:22 XXXXXXXXX user.debug dhcpc-event[1679]: *** bound, wan_ipaddr=100.98.158.107
Jan 29 17:22:22 XXXXXXXXX user.debug dhcpc-event[1679]: *** bound, wan_netmask=255.255.255.248
Jan 29 17:22:22 XXXXXXXXX user.debug dhcpc-event[1679]: *** bound, wan_gateway=100.98.158.105
Jan 29 17:22:22 XXXXXXXXX user.debug dhcpc-event[1679]: *** bound, wan_get_dns=185.89.185.2 89.108.202.21
Jan 29 17:22:22 XXXXXXXXX user.debug dhcpc-event[1679]: *** bound, wan_routes1=
Jan 29 17:22:22 XXXXXXXXX user.debug dhcpc-event[1679]: *** bound, wan_routes2=
Jan 29 17:22:22 XXXXXXXXX user.debug dhcpc-event[1679]: *** bound, do ifconfig ...
Jan 29 17:22:22 XXXXXXXXX user.debug dhcpc-event[1679]: OUT bound: to start_wan_done, ifname=vlan2 prefix=wan ...
Jan 29 17:22:22 XXXXXXXXX user.debug dhcpc-event[1679]: MWAN: IN start_wan_done, interface=vlan2 wan_prefix=wan proto=2
Jan 29 17:22:22 XXXXXXXXX user.debug dhcpc-event[1679]: !!! start_wan_done: route_del(vlan2,0,NULL,NULL,NULL) !!! delete default interface route !!!
Jan 29 17:22:22 XXXXXXXXX user.debug dhcpc-event[1679]: !!! start_wan_done: route_add(vlan2,0,100.98.158.105,NULL,255.255.255.255) !!!
Jan 29 17:22:22 XXXXXXXXX user.debug dhcpc-event[1679]: !!! start_wan_done: route_add(vlan2,0,0.0.0.0,100.98.158.105,0.0.0.0) !!! set default gw 100.98.158.105 on vlan2 !!!
Jan 29 17:22:22 XXXXXXXXX daemon.info dnsmasq[1284]: reading /etc/resolv.dnsmasq
Jan 29 17:22:22 XXXXXXXXX daemon.info dnsmasq[1284]: using nameserver 127.0.0.1#5453
Jan 29 17:22:22 XXXXXXXXX daemon.info dnsmasq[1284]: using nameserver 8.8.8.8#53
Jan 29 17:22:22 XXXXXXXXX daemon.info dnsmasq[1284]: using nameserver 8.8.4.4#53
Jan 29 17:22:22 XXXXXXXXX daemon.info dnsmasq[1284]: reading /etc/resolv.dnsmasq
Jan 29 17:22:22 XXXXXXXXX daemon.info dnsmasq[1284]: using nameserver 127.0.0.1#5453
Jan 29 17:22:22 XXXXXXXXX daemon.info dnsmasq[1284]: using nameserver 8.8.8.8#53
Jan 29 17:22:22 XXXXXXXXX daemon.info dnsmasq[1284]: using nameserver 8.8.4.4#53
Jan 29 17:22:22 XXXXXXXXX daemon.info dnsmasq[1284]: exiting on receipt of SIGTERM
Jan 29 17:22:22 XXXXXXXXX daemon.info dnsmasq[1682]: started, version 2.80-ab53883 cachesize 4096
Jan 29 17:22:22 XXXXXXXXX daemon.info dnsmasq[1682]: compile time options: IPv6 GNU-getopt no-RTC no-DBus no-UBus no-i18n no-IDN DHCP DHCPv6 no-Lua TFTP no-conntrack ipset Tomato-helper auth DNSSEC no-ID loop-detect inotify no-dumpfile
Jan 29 17:22:22 XXXXXXXXX daemon.info dnsmasq[1682]: DNSSEC validation enabled
Jan 29 17:22:22 XXXXXXXXX daemon.info dnsmasq[1682]: DNSSEC signature timestamps not checked until receipt of SIGINT
Jan 29 17:22:22 XXXXXXXXX daemon.info dnsmasq[1682]: configured with trust anchor for <root> keytag 20326
Jan 29 17:22:22 XXXXXXXXX daemon.info dnsmasq[1682]: configured with trust anchor for <root> keytag 19036
Jan 29 17:22:22 XXXXXXXXX daemon.info dnsmasq[1682]: asynchronous logging enabled, queue limit is 5 messages
Jan 29 17:22:22 XXXXXXXXX daemon.info dnsmasq-dhcp[1682]: DHCP, IP range 192.168.23.51 -- 192.168.23.254, lease time 1d
Jan 29 17:22:22 XXXXXXXXX daemon.info dnsmasq-dhcp[1682]: DHCP, IP range 192.168.13.51 -- 192.168.13.254, lease time 1d
Jan 29 17:22:22 XXXXXXXXX daemon.info dnsmasq-dhcp[1682]: DHCP, IP range 192.168.3.51 -- 192.168.3.254, lease time 1d
Jan 29 17:22:22 XXXXXXXXX daemon.info dnsmasq[1682]: using nameserver 127.0.0.1#5453
Jan 29 17:22:22 XXXXXXXXX daemon.info dnsmasq[1682]: reading /etc/resolv.dnsmasq
Jan 29 17:22:22 XXXXXXXXX daemon.info dnsmasq[1682]: using nameserver 127.0.0.1#5453
Jan 29 17:22:22 XXXXXXXXX daemon.info dnsmasq[1682]: using nameserver 8.8.8.8#53
Jan 29 17:22:22 XXXXXXXXX daemon.info dnsmasq[1682]: using nameserver 8.8.4.4#53
Jan 29 17:22:22 XXXXXXXXX daemon.info dnsmasq[1682]: read /etc/hosts - 16 addresses
Jan 29 17:22:22 XXXXXXXXX daemon.info dnsmasq[1682]: read /etc/dnsmasq/hosts - 12 addresses
Jan 29 17:22:22 XXXXXXXXX daemon.info dnsmasq[1682]: read /etc/dnsmasq/dhcp-hosts - 0 addresses
Jan 29 17:22:22 XXXXXXXXX daemon.info dnsmasq-dhcp[1682]: read /etc/dnsmasq/hosts
Jan 29 17:22:22 XXXXXXXXX daemon.info dnsmasq-dhcp[1682]: read /etc/dnsmasq/dhcp-hosts
Jan 29 17:22:22 XXXXXXXXX user.info preinit[1]: Starting stubby 0.2.6, DNS-o-TLS Proxy
Jan 29 17:22:22 XXXXXXXXX daemon.debug stubby[1703]: Read config from file /etc/stubby.yml
Jan 29 17:22:23 XXXXXXXXX daemon.info dnsmasq[1682]: now checking DNSSEC signature timestamps
Jan 29 17:22:23 XXXXXXXXX daemon.info dnsmasq[1682]: read /etc/hosts - 16 addresses
Jan 29 17:22:23 XXXXXXXXX daemon.info dnsmasq[1682]: read /etc/dnsmasq/hosts - 12 addresses
Jan 29 17:22:23 XXXXXXXXX daemon.info dnsmasq[1682]: read /etc/dnsmasq/dhcp-hosts - 0 addresses
Jan 29 17:22:23 XXXXXXXXX daemon.info dnsmasq-dhcp[1682]: read /etc/dnsmasq/hosts
Jan 29 17:22:23 XXXXXXXXX daemon.info dnsmasq-dhcp[1682]: read /etc/dnsmasq/dhcp-hosts
Jan 29 17:22:24 XXXXXXXXX daemon.info dnsmasq-dhcp[1682]: DHCPDISCOVER(br1) 84:f3:eb:48:9c:46 
Jan 29 17:22:24 XXXXXXXXX daemon.info dnsmasq-dhcp[1682]: DHCPOFFER(br1) 192.168.13.23 84:f3:eb:48:9c:46 
Jan 29 17:22:24 XXXXXXXXX daemon.info dnsmasq-dhcp[1682]: DHCPREQUEST(br1) 192.168.13.23 84:f3:eb:48:9c:46 
Jan 29 17:22:24 XXXXXXXXX daemon.info dnsmasq-dhcp[1682]: DHCPACK(br1) 192.168.13.23 84:f3:eb:48:9c:46 Sonoff_01
Jan 29 17:22:24 XXXXXXXXX user.debug dhcpc-event[1679]: MWAN: do_wan_routes, interface=vlan2 metric=0 add=1 prefix=wan
Jan 29 17:22:24 XXXXXXXXX user.debug dhcpc-event[1679]: MWAN: wan is primary: true
Jan 29 17:22:25 XXXXXXXXX daemon.err nmbd[1070]: *****
Jan 29 17:22:25 XXXXXXXXX daemon.err nmbd[1070]: 
Jan 29 17:22:25 XXXXXXXXX daemon.err nmbd[1070]: Samba server XXXXXXXXX is now a domain master browser for workgroup WORKGROUP on subnet 192.168.3.1
Jan 29 17:22:25 XXXXXXXXX daemon.err nmbd[1070]: 
Jan 29 17:22:25 XXXXXXXXX daemon.err nmbd[1070]: *****
Jan 29 17:22:25 XXXXXXXXX user.info dhcpc-event[1679]: igmpproxy is stopped
Jan 29 17:22:28 XXXXXXXXX user.debug dhcpc-event[1679]: IN mwan_table_add
Jan 29 17:22:28 XXXXXXXXX user.debug dhcpc-event[1679]: IN mwan_table_del
Jan 29 17:22:28 XXXXXXXXX user.debug dhcpc-event[1679]: wan, cmd=ip rule del table 1 pref 101
Jan 29 17:22:28 XXXXXXXXX user.debug dhcpc-event[1679]: wan, cmd=ip rule del table 1 pref 111
Jan 29 17:22:28 XXXXXXXXX user.debug dhcpc-event[1679]: wan, cmd=ip rule del table 1 pref 111
Jan 29 17:22:28 XXXXXXXXX user.debug dhcpc-event[1679]: wan, cmd=ip rule del table 1 pref 111
Jan 29 17:22:28 XXXXXXXXX user.debug dhcpc-event[1679]: wan, cmd=ip rule del table 1 pref 121
Jan 29 17:22:28 XXXXXXXXX user.debug dhcpc-event[1679]: OUT mwan_table_del
Jan 29 17:22:28 XXXXXXXXX user.debug dhcpc-event[1679]: MWAN: OUT start_wan_done [wan]
Jan 29 17:22:33 XXXXXXXXX daemon.err nmbd[1070]: *****
Jan 29 17:22:33 XXXXXXXXX daemon.err nmbd[1070]: 
Jan 29 17:22:33 XXXXXXXXX daemon.err nmbd[1070]: Samba name server XXXXXXXXX is now a local master browser for workgroup WORKGROUP on subnet 192.168.3.1
Jan 29 17:22:33 XXXXXXXXX daemon.err nmbd[1070]: 
Jan 29 17:22:33 XXXXXXXXX daemon.err nmbd[1070]: *****
Jan 29 17:22:57 XXXXXXXXX user.info adblock[1888]: prepare to download ...
Jan 29 17:22:57 XXXXXXXXX user.info adblock[1888]: [1] downloading blacklist - http://winhelp2002.mvps.org/hosts.txt
Jan 29 17:22:59 XXXXXXXXX daemon.info dnsmasq-dhcp[1682]: DHCPDISCOVER(br0) 38:d5:47:9c:a6:53 
Jan 29 17:22:59 XXXXXXXXX daemon.info dnsmasq-dhcp[1682]: DHCPOFFER(br0) 192.168.3.102 38:d5:47:9c:a6:53 
Jan 29 17:22:59 XXXXXXXXX daemon.info dnsmasq-dhcp[1682]: DHCPREQUEST(br0) 192.168.3.102 38:d5:47:9c:a6:53 
Jan 29 17:22:59 XXXXXXXXX daemon.info dnsmasq-dhcp[1682]: DHCPACK(br0) 192.168.3.102 38:d5:47:9c:a6:53 android-ed9557b71bc0bf48
Jan 29 17:23:00 XXXXXXXXX user.info adblock[1888]: ... [1] found 12033 entries
Jan 29 17:23:00 XXXXXXXXX user.info adblock[1888]: [2] downloading blacklist - http://adaway.org/hosts.txt
Jan 29 17:23:02 XXXXXXXXX user.info adblock[1888]: ... [2] found 18929 entries
Jan 29 17:23:02 XXXXXXXXX user.info adblock[1888]: [3] downloading blacklist - http://hosts-file.net/ad_servers.txt
Jan 29 17:23:05 XXXXXXXXX user.info adblock[1888]: ... [3] found 45747 entries
Jan 29 17:23:05 XXXXXXXXX user.info adblock[1888]: [4] downloading blacklist - http://www.malwaredomainlist.com/hostslist/hosts.txt
Jan 29 17:23:06 XXXXXXXXX user.info adblock[1888]: ... [4] found 1110 entries
Jan 29 17:23:07 XXXXXXXXX user.info adblock[1888]: [5] downloading blacklist - http://pgl.yoyo.org/adservers/serverlist.php?hostformat=hosts&mimetype=plaintext
Jan 29 17:23:07 XXXXXXXXX user.info adblock[1888]: ... [5] found 3309 entries
Jan 29 17:23:07 XXXXXXXXX user.info adblock[1888]: [6] downloading blacklist - https://raw.githubusercontent.com/hoshsadiq/adblock-nocoin-list/master/hosts.txt
Jan 29 17:23:08 XXXXXXXXX cron.err crond[1037]: time disparity of 26338582 minutes detected
Jan 29 17:23:08 XXXXXXXXX user.info adblock[1888]: ... [6] found 704 entries
Jan 29 17:23:08 XXXXXXXXX user.info adblock[1888]: skip disabled blacklist - http://someonewhocares.org/hosts/zero/hosts
Jan 29 17:23:08 XXXXXXXXX user.info adblock[1888]: [7] downloading blacklist - https://raw.githubusercontent.com/WindowsLies/BlockWindows/master/hosts
Jan 29 17:23:09 XXXXXXXXX user.info adblock[1888]: ... [7] found 109 entries
Jan 29 17:23:09 XXXXXXXXX user.info adblock[1888]: skip disabled blacklist - http://sysctl.org/cameleon/hosts
Jan 29 17:23:09 XXXXXXXXX user.info adblock[1888]: skip disabled blacklist - http://adblock.gjtech.net/?format=hostfile
Jan 29 17:23:09 XXXXXXXXX user.info adblock[1888]: skip disabled blacklist - http://hostsfile.mine.nu/Hosts
Jan 29 17:23:09 XXXXXXXXX user.info adblock[1888]: skip disabled blacklist - https://raw.github.com/notracking/hosts-blocklists/master/hostnames.txt
Jan 29 17:23:19 XXXXXXXXX user.info adblock[1888]: activated - 67266 entries
Jan 29 17:23:19 XXXXXXXXX user.info adblock[1888]: add cron job
Jan 29 17:23:19 XXXXXXXXX daemon.info dnsmasq[1682]: exiting on receipt of SIGTERM
Jan 29 17:23:20 XXXXXXXXX daemon.info dnsmasq[2360]: started, version 2.80-ab53883 cachesize 4096
Jan 29 17:23:20 XXXXXXXXX daemon.info dnsmasq[2360]: compile time options: IPv6 GNU-getopt no-RTC no-DBus no-UBus no-i18n no-IDN DHCP DHCPv6 no-Lua TFTP no-conntrack ipset Tomato-helper auth DNSSEC no-ID loop-detect inotify no-dumpfile
Jan 29 17:23:20 XXXXXXXXX daemon.info dnsmasq[2360]: DNSSEC validation enabled
Jan 29 17:23:20 XXXXXXXXX daemon.info dnsmasq[2360]: DNSSEC signature timestamps not checked until receipt of SIGINT
Jan 29 17:23:20 XXXXXXXXX daemon.info dnsmasq[2360]: configured with trust anchor for <root> keytag 20326
Jan 29 17:23:20 XXXXXXXXX daemon.info dnsmasq[2360]: configured with trust anchor for <root> keytag 19036
Jan 29 17:23:20 XXXXXXXXX daemon.info dnsmasq[2360]: asynchronous logging enabled, queue limit is 5 messages
Jan 29 17:23:20 XXXXXXXXX daemon.info dnsmasq-dhcp[2360]: DHCP, IP range 192.168.23.51 -- 192.168.23.254, lease time 1d
Jan 29 17:23:20 XXXXXXXXX daemon.info dnsmasq-dhcp[2360]: DHCP, IP range 192.168.13.51 -- 192.168.13.254, lease time 1d
Jan 29 17:23:20 XXXXXXXXX daemon.info dnsmasq-dhcp[2360]: DHCP, IP range 192.168.3.51 -- 192.168.3.254, lease time 1d
Jan 29 17:23:20 XXXXXXXXX daemon.info dnsmasq[2360]: using nameserver 127.0.0.1#5453
Jan 29 17:23:20 XXXXXXXXX daemon.info dnsmasq[2360]: reading /etc/resolv.dnsmasq
Jan 29 17:23:20 XXXXXXXXX daemon.info dnsmasq[2360]: using nameserver 127.0.0.1#5453
Jan 29 17:23:20 XXXXXXXXX daemon.info dnsmasq[2360]: using nameserver 8.8.8.8#53
Jan 29 17:23:20 XXXXXXXXX daemon.info dnsmasq[2360]: using nameserver 8.8.4.4#53
Jan 29 17:23:20 XXXXXXXXX daemon.info dnsmasq[2360]: read /etc/hosts - 4 addresses
Jan 29 17:23:20 XXXXXXXXX daemon.info dnsmasq[2360]: read /etc/dnsmasq/hosts - 12 addresses
Jan 29 17:23:20 XXXXXXXXX daemon.info dnsmasq[2360]: read /etc/dnsmasq/dhcp-hosts - 0 addresses
Jan 29 17:23:20 XXXXXXXXX daemon.info dnsmasq-dhcp[2360]: read /etc/dnsmasq/hosts
Jan 29 17:23:20 XXXXXXXXX daemon.info dnsmasq-dhcp[2360]: read /etc/dnsmasq/dhcp-hosts
Jan 29 17:23:21 XXXXXXXXX user.info preinit[1]: Starting stubby 0.2.6, DNS-o-TLS Proxy
Jan 29 17:23:21 XXXXXXXXX daemon.debug stubby[2381]: Read config from file /etc/stubby.yml
Jan 29 17:23:21 XXXXXXXXX daemon.info dnsmasq-dhcp[2360]: DHCPDISCOVER(br0) d0:b1:28:e1:c6:97 
Jan 29 17:23:21 XXXXXXXXX daemon.info dnsmasq-dhcp[2360]: DHCPOFFER(br0) 192.168.3.14 d0:b1:28:e1:c6:97 
Jan 29 17:23:21 XXXXXXXXX daemon.info dnsmasq-dhcp[2360]: DHCPREQUEST(br0) 192.168.3.14 d0:b1:28:e1:c6:97 
Jan 29 17:23:21 XXXXXXXXX daemon.info dnsmasq-dhcp[2360]: DHCPACK(br0) 192.168.3.14 d0:b1:28:e1:c6:97 GalaxyTabA2016
Jan 29 17:23:22 XXXXXXXXX daemon.info dnsmasq[2360]: now checking DNSSEC signature timestamps
Jan 29 17:23:22 XXXXXXXXX daemon.info dnsmasq[2360]: read /etc/hosts - 16 addresses
Jan 29 17:23:22 XXXXXXXXX daemon.info dnsmasq[2360]: read /etc/dnsmasq/hosts - 12 addresses
Jan 29 17:23:22 XXXXXXXXX daemon.info dnsmasq[2360]: read /etc/dnsmasq/dhcp-hosts - 0 addresses
Jan 29 17:23:22 XXXXXXXXX daemon.info dnsmasq-dhcp[2360]: read /etc/dnsmasq/hosts
Jan 29 17:23:22 XXXXXXXXX daemon.info dnsmasq-dhcp[2360]: read /etc/dnsmasq/dhcp-hosts
Jan 29 17:24:00 XXXXXXXXX user.debug watchdog[2411]: wan metric=101 iface=vlan2 uptime=98
Jan 29 17:24:00 XXXXXXXXX user.debug watchdog[2411]: ISGW=1, WEIGHT=1
Jan 29 17:24:00 XXXXXXXXX user.debug watchdog[2411]: run tracert-test
Jan 29 17:24:57 XXXXXXXXX user.debug watchdog[2411]: tracert for vlan2: RX2=3147016 RX1=3083193
Jan 29 17:25:00 XXXXXXXXX user.debug watchdog[3001]: wan metric=101 iface=vlan2 uptime=158
Jan 29 17:25:00 XXXXXXXXX user.debug watchdog[3001]: ISGW=1, WEIGHT=1
Jan 29 17:25:00 XXXXXXXXX user.debug watchdog[3001]: run tracert-test
Jan 29 17:25:55 XXXXXXXXX user.debug watchdog[3001]: tracert for vlan2: RX2=3210145 RX1=3149410
Jan 29 17:27:00 XXXXXXXXX user.debug watchdog[4139]: wan metric=101 iface=vlan2 uptime=278
Jan 29 17:27:00 XXXXXXXXX user.debug watchdog[4139]: ISGW=1, WEIGHT=1
Jan 29 17:27:00 XXXXXXXXX user.debug watchdog[4139]: run tracert-test
Jan 29 17:27:24 XXXXXXXXX daemon.err stubby[2382]:    *FAILURE* no valid transports or upstreams available!
Jan 29 17:27:27 XXXXXXXXX daemon.info dnsmasq-dhcp[2360]: DHCPDISCOVER(br1) 84:f3:eb:48:9c:46 
Jan 29 17:27:27 XXXXXXXXX daemon.info dnsmasq-dhcp[2360]: DHCPOFFER(br1) 192.168.13.23 84:f3:eb:48:9c:46 
Jan 29 17:27:27 XXXXXXXXX daemon.info dnsmasq-dhcp[2360]: DHCPREQUEST(br1) 192.168.13.23 84:f3:eb:48:9c:46 
Jan 29 17:27:27 XXXXXXXXX daemon.info dnsmasq-dhcp[2360]: DHCPACK(br1) 192.168.13.23 84:f3:eb:48:9c:46 Sonoff_01
Jan 29 17:27:27 XXXXXXXXX daemon.err stubby[2382]:    *FAILURE* no valid transports or upstreams available!
Jan 29 17:27:53 XXXXXXXXX daemon.err stubby[2382]:    *FAILURE* no valid transports or upstreams available!
Jan 29 17:27:56 XXXXXXXXX daemon.err stubby[2382]:    *FAILURE* no valid transports or upstreams available!
Jan 29 17:28:01 XXXXXXXXX user.debug watchdog[4139]: tracert for vlan2: RX2=3237052 RX1=3229775
Jan 29 17:28:03 XXXXXXXXX daemon.err stubby[2382]:    *FAILURE* no valid transports or upstreams available!
Jan 29 17:28:03 XXXXXXXXX daemon.err stubby[2382]:    *FAILURE* no valid transports or upstreams available!
Jan 29 17:28:24 XXXXXXXXX daemon.err stubby[2382]:    *FAILURE* no valid transports or upstreams available!
Jan 29 17:28:24 XXXXXXXXX daemon.err stubby[2382]:    *FAILURE* no valid transports or upstreams available!
Jan 29 17:28:27 XXXXXXXXX daemon.info dnsmasq-dhcp[2360]: DHCPDISCOVER(br1) 84:f3:eb:48:9c:46 
Jan 29 17:28:27 XXXXXXXXX daemon.info dnsmasq-dhcp[2360]: DHCPOFFER(br1) 192.168.13.23 84:f3:eb:48:9c:46 
Jan 29 17:28:27 XXXXXXXXX daemon.info dnsmasq-dhcp[2360]: DHCPREQUEST(br1) 192.168.13.23 84:f3:eb:48:9c:46 
Jan 29 17:28:27 XXXXXXXXX daemon.info dnsmasq-dhcp[2360]: DHCPACK(br1) 192.168.13.23 84:f3:eb:48:9c:46 Sonoff_01
Jan 29 17:28:32 XXXXXXXXX daemon.err stubby[2382]:    *FAILURE* no valid transports or upstreams available!
Jan 29 17:28:32 XXXXXXXXX daemon.err stubby[2382]:    *FAILURE* no valid transports or upstreams available!
Jan 29 17:28:48 XXXXXXXXX daemon.err stubby[2382]:    *FAILURE* no valid transports or upstreams available!
Jan 29 17:28:48 XXXXXXXXX daemon.err stubby[2382]:    *FAILURE* no valid transports or upstreams available!
Jan 29 17:28:48 XXXXXXXXX daemon.err stubby[2382]:    *FAILURE* no valid transports or upstreams available!
Jan 29 17:28:48 XXXXXXXXX daemon.err stubby[2382]:    *FAILURE* no valid transports or upstreams available!
Jan 29 17:29:00 XXXXXXXXX daemon.err stubby[2382]:    *FAILURE* no valid transports or upstreams available!
Jan 29 17:29:00 XXXXXXXXX daemon.err stubby[2382]:    *FAILURE* no valid transports or upstreams available!
Jan 29 17:29:00 XXXXXXXXX user.debug watchdog[5250]: wan metric=101 iface=vlan2 uptime=398
Jan 29 17:29:00 XXXXXXXXX user.debug watchdog[5250]: ISGW=1, WEIGHT=1
Jan 29 17:29:00 XXXXXXXXX user.debug watchdog[5250]: run tracert-test
Jan 29 17:29:09 XXXXXXXXX daemon.err stubby[2382]:    *FAILURE* no valid transports or upstreams available!
Jan 29 17:29:09 XXXXXXXXX daemon.err stubby[2382]:    *FAILURE* no valid transports or upstreams available!
Jan 29 17:29:09 XXXXXXXXX daemon.err stubby[2382]:    *FAILURE* no valid transports or upstreams available!
Jan 29 17:29:19 XXXXXXXXX daemon.err stubby[2382]:    *FAILURE* no valid transports or upstreams available!
Jan 29 17:29:19 XXXXXXXXX daemon.err stubby[2382]:    *FAILURE* no valid transports or upstreams available!
Jan 29 17:29:19 XXXXXXXXX daemon.err stubby[2382]:    *FAILURE* no valid transports or upstreams available!
Jan 29 17:29:25 XXXXXXXXX daemon.err stubby[2382]:    *FAILURE* no valid transports or upstreams available!
Jan 29 17:29:27 XXXXXXXXX daemon.info dnsmasq-dhcp[2360]: DHCPDISCOVER(br1) 84:f3:eb:48:9c:46 
Jan 29 17:29:27 XXXXXXXXX daemon.info dnsmasq-dhcp[2360]: DHCPOFFER(br1) 192.168.13.23 84:f3:eb:48:9c:46 
Jan 29 17:29:27 XXXXXXXXX daemon.info dnsmasq-dhcp[2360]: DHCPREQUEST(br1) 192.168.13.23 84:f3:eb:48:9c:46 
Jan 29 17:29:27 XXXXXXXXX daemon.info dnsmasq-dhcp[2360]: DHCPACK(br1) 192.168.13.23 84:f3:eb:48:9c:46 Sonoff_01
Jan 29 17:29:29 XXXXXXXXX daemon.err stubby[2382]:    *FAILURE* no valid transports or upstreams available!
Jan 29 17:29:29 XXXXXXXXX daemon.err stubby[2382]:    *FAILURE* no valid transports or upstreams available!
Jan 29 17:29:29 XXXXXXXXX daemon.err stubby[2382]:    *FAILURE* no valid transports or upstreams available!
Jan 29 17:29:29 XXXXXXXXX daemon.err stubby[2382]:    *FAILURE* no valid transports or upstreams available!
Jan 29 17:29:33 XXXXXXXXX daemon.err stubby[2382]:    *FAILURE* no valid transports or upstreams available!
Jan 29 17:29:34 XXXXXXXXX user.debug watchdog[5250]: tracert for vlan2: RX2=3239968 RX1=3238926
Jan 29 17:29:56 XXXXXXXXX daemon.err stubby[2382]:    *FAILURE* no valid transports or upstreams available!
Jan 29 17:30:00 XXXXXXXXX user.debug watchdog[5861]: wan metric=101 iface=vlan2 uptime=458
Jan 29 17:30:00 XXXXXXXXX user.debug watchdog[5861]: ISGW=1, WEIGHT=1
Jan 29 17:30:00 XXXXXXXXX user.debug watchdog[5861]: run tracert-test
Jan 29 17:30:03 XXXXXXXXX daemon.err stubby[2382]:    *FAILURE* no valid transports or upstreams available!
Jan 29 17:30:05 XXXXXXXXX daemon.err stubby[2382]:    *FAILURE* no valid transports or upstreams available!
Jan 29 17:30:11 XXXXXXXXX daemon.err stubby[2382]:    *FAILURE* no valid transports or upstreams available!
Jan 29 17:30:16 XXXXXXXXX daemon.err stubby[2382]:    *FAILURE* no valid transports or upstreams available!
Jan 29 17:30:16 XXXXXXXXX daemon.err stubby[2382]:    *FAILURE* no valid transports or upstreams available!
Jan 29 17:30:16 XXXXXXXXX daemon.err stubby[2382]:    *FAILURE* no valid transports or upstreams available!
Jan 29 17:30:16 XXXXXXXXX daemon.err stubby[2382]:    *FAILURE* no valid transports or upstreams available!
Jan 29 17:30:17 XXXXXXXXX daemon.err stubby[2382]:    *FAILURE* no valid transports or upstreams available!
Jan 29 17:30:17 XXXXXXXXX daemon.err stubby[2382]:    *FAILURE* no valid transports or upstreams available!
Jan 29 17:30:21 XXXXXXXXX daemon.err stubby[2382]:    *FAILURE* no valid transports or upstreams available!
Jan 29 17:30:21 XXXXXXXXX daemon.err stubby[2382]:    *FAILURE* no valid transports or upstreams available!
Jan 29 17:30:21 XXXXXXXXX daemon.err stubby[2382]:    *FAILURE* no valid transports or upstreams available!
Jan 29 17:30:27 XXXXXXXXX daemon.err stubby[2382]:    *FAILURE* no valid transports or upstreams available!
Jan 29 17:30:27 XXXXXXXXX daemon.err stubby[2382]:    *FAILURE* no valid transports or upstreams available!
Jan 29 17:30:27 XXXXXXXXX daemon.err stubby[2382]:    *FAILURE* no valid transports or upstreams available!
Jan 29 17:30:27 XXXXXXXXX daemon.err stubby[2382]:    *FAILURE* no valid transports or upstreams available!
Jan 29 17:30:27 XXXXXXXXX daemon.err stubby[2382]:    *FAILURE* no valid transports or upstreams available!
Jan 29 17:30:27 XXXXXXXXX daemon.info dnsmasq-dhcp[2360]: DHCPDISCOVER(br1) 84:f3:eb:48:9c:46 
Jan 29 17:30:27 XXXXXXXXX daemon.info dnsmasq-dhcp[2360]: DHCPOFFER(br1) 192.168.13.23 84:f3:eb:48:9c:46 
Jan 29 17:30:27 XXXXXXXXX daemon.info dnsmasq-dhcp[2360]: DHCPREQUEST(br1) 192.168.13.23 84:f3:eb:48:9c:46 
Jan 29 17:30:27 XXXXXXXXX daemon.info dnsmasq-dhcp[2360]: DHCPACK(br1) 192.168.13.23 84:f3:eb:48:9c:46 Sonoff_01
Jan 29 17:30:29 XXXXXXXXX daemon.err stubby[2382]:    *FAILURE* no valid transports or upstreams available!
Jan 29 17:30:31 XXXXXXXXX daemon.err stubby[2382]:    *FAILURE* no valid transports or upstreams available!
Jan 29 17:30:36 XXXXXXXXX daemon.err stubby[2382]:    *FAILURE* no valid transports or upstreams available!
Jan 29 17:30:36 XXXXXXXXX daemon.err stubby[2382]:    *FAILURE* no valid transports or upstreams available!
Jan 29 17:30:42 XXXXXXXXX daemon.err stubby[2382]:    *FAILURE* no valid transports or upstreams available!
Jan 29 17:30:42 XXXXXXXXX user.debug watchdog[5861]: tracert for vlan2: RX2=3242018 RX1=3240672
Jan 29 17:30:48 XXXXXXXXX daemon.err stubby[2382]:    *FAILURE* no valid transports or upstreams available!
Jan 29 17:30:48 XXXXXXXXX daemon.err stubby[2382]:    *FAILURE* no valid transports or upstreams available!
Jan 29 17:30:48 XXXXXXXXX daemon.err stubby[2382]:    *FAILURE* no valid transports or upstreams available!
Jan 29 17:30:58 XXXXXXXXX daemon.err stubby[2382]:    *FAILURE* no valid transports or upstreams available!
Jan 29 17:31:00 XXXXXXXXX user.debug watchdog[6444]: wan metric=101 iface=vlan2 uptime=518
Jan 29 17:31:00 XXXXXXXXX user.debug watchdog[6444]: ISGW=1, WEIGHT=1
Jan 29 17:31:00 XXXXXXXXX user.debug watchdog[6444]: run tracert-test
Jan 29 17:31:02 XXXXXXXXX daemon.err stubby[2382]:    *FAILURE* no valid transports or upstreams available!
Jan 29 17:31:02 XXXXXXXXX daemon.err stubby[2382]:    *FAILURE* no valid transports or upstreams available!
Jan 29 17:31:02 XXXXXXXXX daemon.err stubby[2382]:    *FAILURE* no valid transports or upstreams available!
Jan 29 17:31:02 XXXXXXXXX daemon.err stubby[2382]:    *FAILURE* no valid transports or upstreams available!
Jan 29 17:31:02 XXXXXXXXX daemon.err stubby[2382]:    *FAILURE* no valid transports or upstreams available!
Jan 29 17:31:02 XXXXXXXXX daemon.err stubby[2382]:    *FAILURE* no valid transports or upstreams available!
Jan 29 17:31:05 XXXXXXXXX daemon.err stubby[2382]:    *FAILURE* no valid transports or upstreams available!
Jan 29 17:31:05 XXXXXXXXX daemon.err stubby[2382]:    *FAILURE* no valid transports or upstreams available!
Jan 29 17:31:05 XXXXXXXXX daemon.err stubby[2382]:    *FAILURE* no valid transports or upstreams available!
Jan 29 17:31:09 XXXXXXXXX daemon.err stubby[2382]:    *FAILURE* no valid transports or upstreams available!
Jan 29 17:31:09 XXXXXXXXX daemon.err stubby[2382]:    *FAILURE* no valid transports or upstreams available!
Jan 29 17:31:09 XXXXXXXXX daemon.err stubby[2382]:    *FAILURE* no valid transports or upstreams available!
Jan 29 17:31:09 XXXXXXXXX daemon.err stubby[2382]:    *FAILURE* no valid transports or upstreams available!
Jan 29 17:31:09 XXXXXXXXX daemon.err stubby[2382]:    *FAILURE* no valid transports or upstreams available!
Jan 29 17:31:09 XXXXXXXXX daemon.err stubby[2382]:    *FAILURE* no valid transports or upstreams available!
Jan 29 17:31:19 XXXXXXXXX daemon.err stubby[2382]:    *FAILURE* no valid transports or upstreams available!
Jan 29 17:31:28 XXXXXXXXX daemon.info dnsmasq-dhcp[2360]: DHCPDISCOVER(br1) 84:f3:eb:48:9c:46 
Jan 29 17:31:28 XXXXXXXXX daemon.info dnsmasq-dhcp[2360]: DHCPOFFER(br1) 192.168.13.23 84:f3:eb:48:9c:46 
Jan 29 17:31:28 XXXXXXXXX daemon.info dnsmasq-dhcp[2360]: DHCPREQUEST(br1) 192.168.13.23 84:f3:eb:48:9c:46 
Jan 29 17:31:28 XXXXXXXXX daemon.info dnsmasq-dhcp[2360]: DHCPACK(br1) 192.168.13.23 84:f3:eb:48:9c:46 Sonoff_01
Jan 29 17:31:29 XXXXXXXXX daemon.err stubby[2382]:    *FAILURE* no valid transports or upstreams available!
Jan 29 17:31:31 XXXXXXXXX daemon.err stubby[2382]:    *FAILURE* no valid transports or upstreams available!
Jan 29 17:31:34 XXXXXXXXX daemon.err stubby[2382]:    *FAILURE* no valid transports or upstreams available!
Jan 29 17:31:34 XXXXXXXXX daemon.err stubby[2382]:    *FAILURE* no valid transports or upstreams available!
Jan 29 17:31:34 XXXXXXXXX daemon.err stubby[2382]:    *FAILURE* no valid transports or upstreams available!
Jan 29 17:31:34 XXXXXXXXX daemon.err stubby[2382]:    *FAILURE* no valid transports or upstreams available!
Jan 29 17:31:34 XXXXXXXXX daemon.err stubby[2382]:    *FAILURE* no valid transports or upstreams available!
Jan 29 17:31:34 XXXXXXXXX daemon.err stubby[2382]:    *FAILURE* no valid transports or upstreams available!
Jan 29 17:31:34 XXXXXXXXX daemon.err stubby[2382]:    *FAILURE* no valid transports or upstreams available!
Jan 29 17:31:34 XXXXXXXXX daemon.err stubby[2382]:    *FAILURE* no valid transports or upstreams available!
Jan 29 17:31:34 XXXXXXXXX daemon.err stubby[2382]:    *FAILURE* no valid transports or upstreams available!
Jan 29 17:31:37 XXXXXXXXX daemon.err stubby[2382]:    *FAILURE* no valid transports or upstreams available!
Jan 29 17:31:37 XXXXXXXXX daemon.err stubby[2382]:    *FAILURE* no valid transports or upstreams available!
Jan 29 17:31:37 XXXXXXXXX daemon.err stubby[2382]:    *FAILURE* no valid transports or upstreams available!
Jan 29 17:31:37 XXXXXXXXX daemon.err stubby[2382]:    *FAILURE* no valid transports or upstreams available!
Jan 29 17:31:37 XXXXXXXXX daemon.err stubby[2382]:    *FAILURE* no valid transports or upstreams available!
Jan 29 17:31:37 XXXXXXXXX daemon.err stubby[2382]:    *FAILURE* no valid transports or upstreams available!
Jan 29 17:31:37 XXXXXXXXX daemon.err stubby[2382]:    *FAILURE* no valid transports or upstreams available!
Jan 29 17:31:37 XXXXXXXXX daemon.err stubby[2382]:    *FAILURE* no valid transports or upstreams available!
Jan 29 17:31:37 XXXXXXXXX daemon.err stubby[2382]:    *FAILURE* no valid transports or upstreams available!
Jan 29 17:31:37 XXXXXXXXX daemon.err stubby[2382]:    *FAILURE* no valid transports or upstreams available!
Jan 29 17:31:40 XXXXXXXXX user.debug watchdog[6444]: tracert for vlan2: RX2=3243708 RX1=3242596
Jan 29 17:31:42 XXXXXXXXX daemon.err stubby[2382]:    *FAILURE* no valid transports or upstreams available!
Jan 29 17:31:42 XXXXXXXXX daemon.err stubby[2382]:    *FAILURE* no valid transports or upstreams available!
Jan 29 17:31:42 XXXXXXXXX daemon.err stubby[2382]:    *FAILURE* no valid transports or upstreams available!
Jan 29 17:31:42 XXXXXXXXX daemon.err stubby[2382]:    *FAILURE* no valid transports or upstreams available!
Jan 29 17:31:42 XXXXXXXXX daemon.err stubby[2382]:    *FAILURE* no valid transports or upstreams available!
Jan 29 17:31:42 XXXXXXXXX daemon.err stubby[2382]:    *FAILURE* no valid transports or upstreams available!
Jan 29 17:31:43 XXXXXXXXX daemon.err stubby[2382]:    *FAILURE* no valid transports or upstreams available!
Jan 29 17:31:45 XXXXXXXXX daemon.err stubby[2382]:    *FAILURE* no valid transports or upstreams available!
Jan 29 17:31:48 XXXXXXXXX daemon.err stubby[2382]:    *FAILURE* no valid transports or upstreams available!
Jan 29 17:31:48 XXXXXXXXX daemon.err stubby[2382]:    *FAILURE* no valid transports or upstreams available!
Jan 29 17:31:48 XXXXXXXXX daemon.err stubby[2382]:    *FAILURE* no valid transports or upstreams available!
Jan 29 17:31:48 XXXXXXXXX daemon.err stubby[2382]:    *FAILURE* no valid transports or upstreams available!
Jan 29 17:31:48 XXXXXXXXX daemon.err stubby[2382]:    *FAILURE* no valid transports or upstreams available!
Jan 29 17:31:48 XXXXXXXXX daemon.err stubby[2382]:    *FAILURE* no valid transports or upstreams available!
Jan 29 17:31:48 XXXXXXXXX daemon.err stubby[2382]:    *FAILURE* no valid transports or upstreams available!
Jan 29 17:31:48 XXXXXXXXX daemon.err stubby[2382]:    *FAILURE* no valid transports or upstreams available!
Jan 29 17:31:50 XXXXXXXXX daemon.err stubby[2382]:    *FAILURE* no valid transports or upstreams available!
Jan 29 17:31:50 XXXXXXXXX daemon.err stubby[2382]:    *FAILURE* no valid transports or upstreams available!
Jan 29 17:31:54 XXXXXXXXX daemon.err stubby[2382]:    *FAILURE* no valid transports or upstreams available!
Jan 29 17:31:54 XXXXXXXXX daemon.err stubby[2382]:    *FAILURE* no valid transports or upstreams available!
Jan 29 17:31:54 XXXXXXXXX daemon.err stubby[2382]:    *FAILURE* no valid transports or upstreams available!
Jan 29 17:31:54 XXXXXXXXX daemon.err stubby[2382]:    *FAILURE* no valid transports or upstreams available!
Jan 29 17:31:54 XXXXXXXXX daemon.err stubby[2382]:    *FAILURE* no valid transports or upstreams available!
Jan 29 17:31:54 XXXXXXXXX daemon.err stubby[2382]:    *FAILURE* no valid transports or upstreams available!
Jan 29 17:32:00 XXXXXXXXX daemon.err stubby[2382]:    *FAILURE* no valid transports or upstreams available!
Jan 29 17:32:00 XXXXXXXXX daemon.err stubby[2382]:    *FAILURE* no valid transports or upstreams available!
Jan 29 17:32:00 XXXXXXXXX daemon.err stubby[2382]:    *FAILURE* no valid transports or upstreams available!
Jan 29 17:32:00 XXXXXXXXX daemon.err stubby[2382]:    *FAILURE* no valid transports or upstreams available!
Jan 29 17:32:00 XXXXXXXXX daemon.err stubby[2382]:    *FAILURE* no valid transports or upstreams available!
Jan 29 17:32:00 XXXXXXXXX daemon.err stubby[2382]:    *FAILURE* no valid transports or upstreams available!
Jan 29 17:32:00 XXXXXXXXX daemon.err stubby[2382]:    *FAILURE* no valid transports or upstreams available!
Jan 29 17:32:00 XXXXXXXXX daemon.err stubby[2382]:    *FAILURE* no valid transports or upstreams available!
Jan 29 17:32:00 XXXXXXXXX daemon.err stubby[2382]:    *FAILURE* no valid transports or upstreams available!
Jan 29 17:32:00 XXXXXXXXX daemon.err stubby[2382]:    *FAILURE* no valid transports or upstreams available!

    ‌

  7. pedro repo owner
    1. It’s not FW bug - this is the fault of the LTE provider (I assume it’s PLAY), which stops traffic outside its gate (when you download a lot), but traffic to the gate works, therefore traceroute is useless,
    2. Don't use a watchdog with a 1-minute check time - it can cause false positives; change it to at least 2 minutes,
    3. Ping has been having trouble working on the router for a long time (even in images by @pl_shibby),
    4. So the best solution is to use curl as a watchdog - if it doesn’t work - blame provider.

    ‌

  9. dworniok reporter

    I tought that watchdog works simpler - target is not reachable --> reset wan.

    Today Curl failed for me too. Changing targets will work? Any advices? Can you describe little bit more, why Play is guilty here?

  10. pedro repo owner

    If you want to avoid false positives, choose the ones near you, eg. if you’re in Poland: wp.pl, onet.pl etc.

  11. dworniok reporter

    Unfortunately I have to disagree.

    1. I don't have false positives (connection ok → reset). I have false negative (connecton nok → no reset). And this is true for traceroute and curl.

    2. Above you thought about this situation probably (in case of missing internet):

    But adress of google.com is unknown and then I can see:

    ‌

    I tried own curl script also:

    ‌

  13. pedro repo owner

    I have 3 (three) different routers (RT-N18U, RT-AC3200, RT-N66U) on LTE connection with 3 Huawei modems on 3 (three) different providers, and all 3 (three) works like a charm.

    If you have problems with something like “traceroute -I google.com —> bad address” don’t blame FreshTomato but - as I said earlier - your 4G provider (or you set something wrong in the router, but this is not a help forum).

    ‌

    root@router:/tmp/home/root# traceroute -I google.com
    traceroute to google.com (216.58.215.78), 30 hops max, 38 byte packets
    1 * * *
    2 * * *
    3 * * *
    4 * * *
    5 * * *
    6 user-89-108-198-2.mobile.playmobile.pl (89.108.198.2) 22.883 ms 20.741 ms 18.878 ms
    7 72.14.213.26 (72.14.213.26) 19.937 ms 19.630 ms 20.912 ms
    8 108.170.250.209 (108.170.250.209) 21.036 ms 21.155 ms 23.786 ms
    9 108.170.234.103 (108.170.234.103) 20.153 ms 20.201 ms 19.907 ms
    10 waw02s16-in-f14.1e100.net (216.58.215.78) 20.921 ms 20.273 ms 19.797 ms

    //EDIT: BTW: in DHCP mode, it is the modem’s task to resume connection, not the router’s. How do you think the router would do this?

  15. dworniok reporter

    At the begining of issue is “6.1. I have Huawei B528 in brigde mode (but was valid for E3272 too)”

    I have 3 (three) different routers (RT-N18U, RT-AC56U, RT-N10U) and each has the same problem.

    I will dig little bit more myself - I got the repo. Thanks for your time.

    //EDIT: In traceroute mode I saw that script measures package count and compare before < after - even without internet, I see RX packages.

  16. pedro repo owner

    At the begining of issue is “6.1. I have Huawei B528 in brigde mode (but was valid for E3272 too)”

    Of course B528 should be in bridge mode, router set to DHCP and it’s modem work to connect/reconnect; but how did you do the same for E3272? 😛

    E3272 requires 4G / LTE mode…

  17. dworniok reporter

    Somehow, ISP (Play in this case), reqires to renew IP. Maybe when modem is together with DHCP, then both are restarted in case of some request from ISP.

    My way of thingning is simple - I loose internet connection, IP renew helps. So I have to find the way to automatize this (I though this is what watchdog is for).

    To answer of your question, In this case I treated both (B528 and E3372) as modem - no matter whether is connected via usb or ethernet.

    ‌

  18. pedro repo owner

    For E3372, the connection must be set to 4G/LTE, both for non-hilink and hilink type (the latter causes problems on most routers with tomato, btw).

  19. dworniok reporter

    I know about 4G/LTE for USB modems. And I know that hiliks are working fine with N10U e.g.

    I spent some time with tomato 😉

  20. pedro repo owner

    All I can say is that the E3372 works just fine connected in 4G/LTE mode.

    If I find the time, I will try to play with modems in DHCP mode. 😉

  21. Log in to comment
Assignee
–
Type
bug
Priority
major
Status
invalid
Votes
0
Watchers
1
Jira: the preferred issue tracker for Bitbucket. Join the team!