Sun Sep 22 10:39:54 2019 kern.notice kernel: [ 0.000000] Linux version 4.9.152 (buildbot@1fcdec9f6eb0) (gcc version 7.3.0 (OpenWrt GCC 7.3.0 r7676-cddd7b4c77) ) #0 Wed Jan 30 12:21:02 2019 Sun Sep 22 10:39:54 2019 kern.debug kernel: [ 0.000000] MyLoader: sysp=d41aa8a5, boardp=3bb52003, parts=bd952718 Sun Sep 22 10:39:54 2019 kern.info kernel: [ 0.000000] bootconsole [early0] enabled Sun Sep 22 10:39:54 2019 kern.info kernel: [ 0.000000] CPU0 revision is: 0001974c (MIPS 74Kc) Sun Sep 22 10:39:54 2019 kern.info kernel: [ 0.000000] SoC: Atheros AR9344 rev 2 Sun Sep 22 10:39:54 2019 kern.info kernel: [ 0.000000] Determined physical RAM map: Sun Sep 22 10:39:54 2019 kern.info kernel: [ 0.000000] memory: 08000000 @ 00000000 (usable) Sun Sep 22 10:39:54 2019 kern.info kernel: [ 0.000000] Initrd not found or empty - disabling initrd Sun Sep 22 10:39:54 2019 kern.warn kernel: [ 0.000000] Primary instruction cache 64kB, VIPT, 4-way, linesize 32 bytes. Sun Sep 22 10:39:54 2019 kern.warn kernel: [ 0.000000] Primary data cache 32kB, 4-way, VIPT, cache aliases, linesize 32 bytes Sun Sep 22 10:39:54 2019 kern.info kernel: [ 0.000000] Zone ranges: Sun Sep 22 10:39:54 2019 kern.info kernel: [ 0.000000] Normal [mem 0x0000000000000000-0x0000000007ffffff] Sun Sep 22 10:39:54 2019 kern.info kernel: [ 0.000000] Movable zone start for each node Sun Sep 22 10:39:54 2019 kern.info kernel: [ 0.000000] Early memory node ranges Sun Sep 22 10:39:54 2019 kern.info kernel: [ 0.000000] node 0: [mem 0x0000000000000000-0x0000000007ffffff] Sun Sep 22 10:39:54 2019 kern.info kernel: [ 0.000000] Initmem setup node 0 [mem 0x0000000000000000-0x0000000007ffffff] Sun Sep 22 10:39:54 2019 kern.debug kernel: [ 0.000000] On node 0 totalpages: 32768 Sun Sep 22 10:39:54 2019 kern.debug kernel: [ 0.000000] free_area_init_node: node 0, pgdat 8044a204, node_mem_map 81000020 Sun Sep 22 10:39:54 2019 kern.debug kernel: [ 0.000000] Normal zone: 256 pages used for memmap Sun Sep 22 10:39:54 2019 kern.debug kernel: [ 0.000000] Normal zone: 0 pages reserved Sun Sep 22 10:39:54 2019 kern.debug kernel: [ 0.000000] Normal zone: 32768 pages, LIFO batch:7 Sun Sep 22 10:39:54 2019 kern.debug kernel: [ 0.000000] pcpu-alloc: s0 r0 d32768 u32768 alloc=1*32768 Sun Sep 22 10:39:54 2019 kern.debug kernel: [ 0.000000] pcpu-alloc: [0] 0 Sun Sep 22 10:39:54 2019 kern.info kernel: [ 0.000000] Built 1 zonelists in Zone order, mobility grouping on. Total pages: 32512 Sun Sep 22 10:39:54 2019 kern.notice kernel: [ 0.000000] Kernel command line: board=TL-WDR4300 console=ttyS0,115200 rootfstype=squashfs noinitrd Sun Sep 22 10:39:54 2019 kern.info kernel: [ 0.000000] PID hash table entries: 512 (order: -1, 2048 bytes) Sun Sep 22 10:39:54 2019 kern.info kernel: [ 0.000000] Dentry cache hash table entries: 16384 (order: 4, 65536 bytes) Sun Sep 22 10:39:54 2019 kern.info kernel: [ 0.000000] Inode-cache hash table entries: 8192 (order: 3, 32768 bytes) Sun Sep 22 10:39:54 2019 kern.info kernel: [ 0.000000] Writing ErrCtl register=00000000 Sun Sep 22 10:39:54 2019 kern.info kernel: [ 0.000000] Readback ErrCtl register=00000000 Sun Sep 22 10:39:54 2019 kern.info kernel: [ 0.000000] Memory: 125004K/131072K available (3388K kernel code, 175K rwdata, 448K rodata, 268K init, 211K bss, 6068K reserved, 0K cma-reserved) Sun Sep 22 10:39:54 2019 kern.info kernel: [ 0.000000] SLUB: HWalign=32, Order=0-3, MinObjects=0, CPUs=1, Nodes=1 Sun Sep 22 10:39:54 2019 kern.info kernel: [ 0.000000] NR_IRQS:51 Sun Sep 22 10:39:54 2019 kern.info kernel: [ 0.000000] Clocks: CPU:560.000MHz, DDR:450.000MHz, AHB:225.000MHz, Ref:40.000MHz Sun Sep 22 10:39:54 2019 kern.info kernel: [ 0.000000] clocksource: MIPS: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 6825930166 ns Sun Sep 22 10:39:54 2019 kern.info kernel: [ 0.000010] sched_clock: 32 bits at 280MHz, resolution 3ns, wraps every 7669584382ns Sun Sep 22 10:39:54 2019 kern.info kernel: [ 0.008318] Calibrating delay loop... 278.93 BogoMIPS (lpj=1394688) Sun Sep 22 10:39:54 2019 kern.info kernel: [ 0.081138] pid_max: default: 32768 minimum: 301 Sun Sep 22 10:39:54 2019 kern.info kernel: [ 0.086165] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes) Sun Sep 22 10:39:54 2019 kern.info kernel: [ 0.093212] Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes) Sun Sep 22 10:39:54 2019 kern.info kernel: [ 0.103425] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns Sun Sep 22 10:39:54 2019 kern.info kernel: [ 0.113917] futex hash table entries: 256 (order: -1, 3072 bytes) Sun Sep 22 10:39:54 2019 kern.info kernel: [ 0.121632] NET: Registered protocol family 16 Sun Sep 22 10:39:54 2019 kern.info kernel: [ 0.127870] MIPS: machine is TP-LINK TL-WDR3600/4300/4310 Sun Sep 22 10:39:54 2019 kern.warn kernel: [ 0.136969] registering PCI controller with io_map_base unset Sun Sep 22 10:39:54 2019 kern.warn kernel: [ 0.380968] Can't analyze schedule() prologue at 803ab684 Sun Sep 22 10:39:54 2019 kern.info kernel: [ 0.397555] PCI host bridge to bus 0000:00 Sun Sep 22 10:39:54 2019 kern.info kernel: [ 0.401926] pci_bus 0000:00: root bus resource [mem 0x10000000-0x13ffffff] Sun Sep 22 10:39:54 2019 kern.info kernel: [ 0.409279] pci_bus 0000:00: root bus resource [io 0x0000] Sun Sep 22 10:39:54 2019 kern.info kernel: [ 0.415206] pci_bus 0000:00: root bus resource [??? 0x00000000 flags 0x0] Sun Sep 22 10:39:54 2019 kern.info kernel: [ 0.422440] pci_bus 0000:00: No busn resource found for root bus, will use [bus 00-ff] Sun Sep 22 10:39:54 2019 kern.debug kernel: [ 0.430902] pci 0000:00:00.0: [168c:0033] type 00 class 0x028000 Sun Sep 22 10:39:54 2019 kern.err kernel: [ 0.430913] pci 0000:00:00.0: invalid calibration data Sun Sep 22 10:39:54 2019 kern.debug kernel: [ 0.436419] pci 0000:00:00.0: reg 0x10: [mem 0x00000000-0x0001ffff 64bit] Sun Sep 22 10:39:54 2019 kern.debug kernel: [ 0.436474] pci 0000:00:00.0: reg 0x30: [mem 0x00000000-0x0000ffff pref] Sun Sep 22 10:39:54 2019 kern.debug kernel: [ 0.436551] pci 0000:00:00.0: supports D1 Sun Sep 22 10:39:54 2019 kern.debug kernel: [ 0.436561] pci 0000:00:00.0: PME# supported from D0 D1 D3hot Sun Sep 22 10:39:54 2019 kern.debug kernel: [ 0.436797] pci_bus 0000:00: busn_res: [bus 00-ff] end is updated to 00 Sun Sep 22 10:39:54 2019 kern.info kernel: [ 0.436827] pci 0000:00:00.0: BAR 0: assigned [mem 0x10000000-0x1001ffff 64bit] Sun Sep 22 10:39:54 2019 kern.info kernel: [ 0.444624] pci 0000:00:00.0: BAR 6: assigned [mem 0x10020000-0x1002ffff pref] Sun Sep 22 10:39:54 2019 kern.info kernel: [ 0.452345] pci 0000:00:00.0: using irq 40 for pin 1 Sun Sep 22 10:39:54 2019 kern.info kernel: [ 0.460874] clocksource: Switched to clocksource MIPS Sun Sep 22 10:39:54 2019 kern.info kernel: [ 0.467358] NET: Registered protocol family 2 Sun Sep 22 10:39:54 2019 kern.info kernel: [ 0.472971] TCP established hash table entries: 1024 (order: 0, 4096 bytes) Sun Sep 22 10:39:54 2019 kern.info kernel: [ 0.480396] TCP bind hash table entries: 1024 (order: 0, 4096 bytes) Sun Sep 22 10:39:54 2019 kern.info kernel: [ 0.487220] TCP: Hash tables configured (established 1024 bind 1024) Sun Sep 22 10:39:54 2019 kern.info kernel: [ 0.494081] UDP hash table entries: 256 (order: 0, 4096 bytes) Sun Sep 22 10:39:54 2019 kern.info kernel: [ 0.500306] UDP-Lite hash table entries: 256 (order: 0, 4096 bytes) Sun Sep 22 10:39:54 2019 kern.info kernel: [ 0.507259] NET: Registered protocol family 1 Sun Sep 22 10:39:54 2019 kern.debug kernel: [ 0.511977] PCI: CLS 0 bytes, default 32 Sun Sep 22 10:39:54 2019 kern.warn kernel: [ 0.514780] Crashlog allocated RAM at address 0x3f00000 Sun Sep 22 10:39:54 2019 kern.info kernel: [ 0.521496] workingset: timestamp_bits=30 max_order=15 bucket_order=0 Sun Sep 22 10:39:54 2019 kern.info kernel: [ 0.535669] squashfs: version 4.0 (2009/01/31) Phillip Lougher Sun Sep 22 10:39:54 2019 kern.info kernel: [ 0.541914] jffs2: version 2.2 (NAND) (SUMMARY) (LZMA) (RTIME) (CMODE_PRIORITY) (c) 2001-2006 Red Hat, Inc. Sun Sep 22 10:39:54 2019 kern.info kernel: [ 0.561640] io scheduler noop registered Sun Sep 22 10:39:54 2019 kern.info kernel: [ 0.565813] io scheduler deadline registered (default) Sun Sep 22 10:39:54 2019 kern.info kernel: [ 0.571745] Serial: 8250/16550 driver, 16 ports, IRQ sharing enabled Sun Sep 22 10:39:54 2019 kern.info kernel: [ 0.581346] console [ttyS0] disabled Sun Sep 22 10:39:54 2019 kern.info kernel: [ 0.605226] serial8250.0: ttyS0 at MMIO 0x18020000 (irq = 11, base_baud = 2500000) is a 16550A Sun Sep 22 10:39:54 2019 kern.info kernel: [ 0.614420] console [ttyS0] enabled Sun Sep 22 10:39:54 2019 kern.info kernel: [ 0.621841] bootconsole [early0] disabled Sun Sep 22 10:39:54 2019 kern.warn kernel: [ 0.636404] m25p80 spi0.0: found s25fl064k, expected m25p80 Sun Sep 22 10:39:54 2019 kern.info kernel: [ 0.654419] m25p80 spi0.0: s25fl064k (8192 Kbytes) Sun Sep 22 10:39:54 2019 kern.notice kernel: [ 0.659578] 5 tp-link partitions found on MTD device spi0.0 Sun Sep 22 10:39:54 2019 kern.notice kernel: [ 0.665270] Creating 5 MTD partitions on "spi0.0": Sun Sep 22 10:39:54 2019 kern.notice kernel: [ 0.670140] 0x000000000000-0x000000020000 : "u-boot" Sun Sep 22 10:39:54 2019 kern.notice kernel: [ 0.677081] 0x000000020000-0x000000170364 : "kernel" Sun Sep 22 10:39:54 2019 kern.notice kernel: [ 0.684165] 0x000000170364-0x0000007f0000 : "rootfs" Sun Sep 22 10:39:54 2019 kern.notice kernel: [ 0.690802] mtd: device 2 (rootfs) set to be root filesystem Sun Sep 22 10:39:54 2019 kern.notice kernel: [ 0.696630] 1 squashfs-split partitions found on MTD device rootfs Sun Sep 22 10:39:54 2019 kern.notice kernel: [ 0.702920] 0x0000003c0000-0x0000007f0000 : "rootfs_data" Sun Sep 22 10:39:54 2019 kern.notice kernel: [ 0.710527] 0x0000007f0000-0x000000800000 : "art" Sun Sep 22 10:39:54 2019 kern.notice kernel: [ 0.717388] 0x000000020000-0x0000007f0000 : "firmware" Sun Sep 22 10:39:54 2019 kern.info kernel: [ 0.725660] libphy: Fixed MDIO Bus: probed Sun Sep 22 10:39:54 2019 kern.info kernel: [ 0.741816] switch0: Atheros AR8327 rev. 4 switch registered on ag71xx-mdio.0 Sun Sep 22 10:39:54 2019 kern.info kernel: [ 1.413252] libphy: ag71xx_mdio: probed Sun Sep 22 10:39:54 2019 kern.info kernel: [ 2.042268] ag71xx ag71xx.0: connected to PHY at ag71xx-mdio.0:00 [uid=004dd034, driver=Atheros AR8216/AR8236/AR8316] Sun Sep 22 10:39:54 2019 kern.info kernel: [ 2.053788] eth0: Atheros AG71xx at 0xb9000000, irq 4, mode:RGMII Sun Sep 22 10:39:54 2019 kern.info kernel: [ 2.062153] NET: Registered protocol family 10 Sun Sep 22 10:39:54 2019 kern.info kernel: [ 2.070418] NET: Registered protocol family 17 Sun Sep 22 10:39:54 2019 kern.info kernel: [ 2.075039] bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Sun Sep 22 10:39:54 2019 kern.info kernel: [ 2.088305] 8021q: 802.1Q VLAN Support v1.8 Sun Sep 22 10:39:54 2019 kern.info kernel: [ 2.094275] hctosys: unable to open rtc device (rtc0) Sun Sep 22 10:39:54 2019 kern.info kernel: [ 2.104184] VFS: Mounted root (squashfs filesystem) readonly on device 31:2. Sun Sep 22 10:39:54 2019 kern.info kernel: [ 2.112620] Freeing unused kernel memory: 268K Sun Sep 22 10:39:54 2019 kern.warn kernel: [ 2.117132] This architecture does not have kernel memory protection. Sun Sep 22 10:39:54 2019 kern.notice kernel: [ 2.560887] random: fast init done Sun Sep 22 10:39:54 2019 user.info kernel: [ 2.817791] init: Console is alive Sun Sep 22 10:39:54 2019 user.info kernel: [ 2.821525] init: - watchdog - Sun Sep 22 10:39:54 2019 user.info kernel: [ 3.778344] kmodloader: loading kernel modules from /etc/modules-boot.d/* Sun Sep 22 10:39:54 2019 kern.info kernel: [ 3.846952] usbcore: registered new interface driver usbfs Sun Sep 22 10:39:54 2019 kern.info kernel: [ 3.852672] usbcore: registered new interface driver hub Sun Sep 22 10:39:54 2019 kern.info kernel: [ 3.858167] usbcore: registered new device driver usb Sun Sep 22 10:39:54 2019 kern.info kernel: [ 3.868530] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver Sun Sep 22 10:39:54 2019 kern.info kernel: [ 3.876752] ehci-platform: EHCI generic platform driver Sun Sep 22 10:39:54 2019 kern.info kernel: [ 3.882201] ehci-platform ehci-platform: EHCI Host Controller Sun Sep 22 10:39:54 2019 kern.info kernel: [ 3.888075] ehci-platform ehci-platform: new USB bus registered, assigned bus number 1 Sun Sep 22 10:39:54 2019 kern.info kernel: [ 3.898222] ehci-platform ehci-platform: irq 3, io mem 0x1b000000 Sun Sep 22 10:39:54 2019 kern.info kernel: [ 3.930914] ehci-platform ehci-platform: USB 2.0 started, EHCI 1.00 Sun Sep 22 10:39:54 2019 kern.info kernel: [ 3.938191] hub 1-0:1.0: USB hub found Sun Sep 22 10:39:54 2019 kern.info kernel: [ 3.942384] hub 1-0:1.0: 1 port detected Sun Sep 22 10:39:54 2019 user.info kernel: [ 3.947203] kmodloader: done loading kernel modules from /etc/modules-boot.d/* Sun Sep 22 10:39:54 2019 user.info kernel: [ 3.965002] init: - preinit - Sun Sep 22 10:39:54 2019 kern.info kernel: [ 4.550955] usb 1-1: new high-speed USB device number 2 using ehci-platform Sun Sep 22 10:39:54 2019 kern.notice kernel: [ 4.787258] random: procd: uninitialized urandom read (4 bytes read) Sun Sep 22 10:39:54 2019 kern.info kernel: [ 4.794221] hub 1-1:1.0: USB hub found Sun Sep 22 10:39:54 2019 kern.info kernel: [ 4.801114] hub 1-1:1.0: 4 ports detected Sun Sep 22 10:39:54 2019 kern.info kernel: [ 4.812713] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready Sun Sep 22 10:39:54 2019 kern.info kernel: [ 5.872125] eth0: link up (1000Mbps/Full duplex) Sun Sep 22 10:39:54 2019 kern.info kernel: [ 5.876900] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready Sun Sep 22 10:39:54 2019 kern.notice kernel: [ 8.191805] jffs2: notice: (438) jffs2_build_xattr_subsystem: complete building xattr subsystem, 0 of xdatum (0 unchecked, 0 orphan) and 0 of xref (0 dead, 0 orphan) found. Sun Sep 22 10:39:54 2019 user.info kernel: [ 8.210600] mount_root: switching to jffs2 overlay Sun Sep 22 10:39:54 2019 user.warn kernel: [ 8.244562] urandom-seed: Seeding with /etc/urandom.seed Sun Sep 22 10:39:54 2019 kern.info kernel: [ 8.472859] eth0: link down Sun Sep 22 10:39:54 2019 user.info kernel: [ 8.491253] procd: - early - Sun Sep 22 10:39:54 2019 user.info kernel: [ 8.494277] procd: - watchdog - Sun Sep 22 10:39:54 2019 user.info kernel: [ 9.224158] procd: - watchdog - Sun Sep 22 10:39:54 2019 user.info kernel: [ 9.227628] procd: - ubus - Sun Sep 22 10:39:54 2019 kern.notice kernel: [ 9.246335] random: ubusd: uninitialized urandom read (4 bytes read) Sun Sep 22 10:39:54 2019 kern.notice kernel: [ 9.282748] random: ubusd: uninitialized urandom read (4 bytes read) Sun Sep 22 10:39:54 2019 kern.notice kernel: [ 9.289717] random: ubusd: uninitialized urandom read (4 bytes read) Sun Sep 22 10:39:54 2019 user.info kernel: [ 9.297321] procd: - init - Sun Sep 22 10:39:54 2019 user.info kernel: [ 9.767146] kmodloader: loading kernel modules from /etc/modules.d/* Sun Sep 22 10:39:54 2019 kern.info kernel: [ 9.784636] tun: Universal TUN/TAP device driver, 1.6 Sun Sep 22 10:39:54 2019 kern.info kernel: [ 9.789766] tun: (C) 1999-2004 Max Krasnyansky Sun Sep 22 10:39:54 2019 kern.info kernel: [ 9.801084] ip6_tables: (C) 2000-2006 Netfilter Core Team Sun Sep 22 10:39:54 2019 kern.info kernel: [ 9.819197] Loading modules backported from Linux version wt-2017-11-01-0-gfe248fc2c180 Sun Sep 22 10:39:54 2019 kern.info kernel: [ 9.827382] Backport generated by backports.git v4.14-rc2-1-31-g86cf0e5d Sun Sep 22 10:39:54 2019 kern.info kernel: [ 9.838366] ip_tables: (C) 2000-2006 Netfilter Core Team Sun Sep 22 10:39:54 2019 kern.info kernel: [ 9.852741] nf_conntrack version 0.5.0 (2048 buckets, 8192 max) Sun Sep 22 10:39:54 2019 kern.info kernel: [ 9.913987] xt_time: kernel timezone is -0000 Sun Sep 22 10:39:54 2019 kern.info kernel: [ 9.972959] PPP generic driver version 2.4.2 Sun Sep 22 10:39:54 2019 kern.info kernel: [ 9.979703] NET: Registered protocol family 24 Sun Sep 22 10:39:54 2019 kern.debug kernel: [ 10.024587] ath: EEPROM regdomain: 0x0 Sun Sep 22 10:39:54 2019 kern.debug kernel: [ 10.024596] ath: EEPROM indicates default country code should be used Sun Sep 22 10:39:54 2019 kern.debug kernel: [ 10.024600] ath: doing EEPROM country->regdmn map search Sun Sep 22 10:39:54 2019 kern.debug kernel: [ 10.024615] ath: country maps to regdmn code: 0x3a Sun Sep 22 10:39:54 2019 kern.debug kernel: [ 10.024621] ath: Country alpha2 being used: US Sun Sep 22 10:39:54 2019 kern.debug kernel: [ 10.024625] ath: Regpair used: 0x3a Sun Sep 22 10:39:54 2019 kern.debug kernel: [ 10.036862] ieee80211 phy0: Selected rate control algorithm 'minstrel_ht' Sun Sep 22 10:39:54 2019 kern.info kernel: [ 10.040740] ieee80211 phy0: Atheros AR9340 Rev:2 mem=0xb8100000, irq=47 Sun Sep 22 10:39:54 2019 kern.warn kernel: [ 10.047697] PCI: Enabling device 0000:00:00.0 (0000 -> 0002) Sun Sep 22 10:39:54 2019 kern.debug kernel: [ 10.058897] ath: EEPROM regdomain: 0x0 Sun Sep 22 10:39:54 2019 kern.debug kernel: [ 10.058904] ath: EEPROM indicates default country code should be used Sun Sep 22 10:39:54 2019 kern.debug kernel: [ 10.058908] ath: doing EEPROM country->regdmn map search Sun Sep 22 10:39:54 2019 kern.debug kernel: [ 10.058923] ath: country maps to regdmn code: 0x3a Sun Sep 22 10:39:54 2019 kern.debug kernel: [ 10.058929] ath: Country alpha2 being used: US Sun Sep 22 10:39:54 2019 kern.debug kernel: [ 10.058933] ath: Regpair used: 0x3a Sun Sep 22 10:39:54 2019 kern.debug kernel: [ 10.069260] ieee80211 phy1: Selected rate control algorithm 'minstrel_ht' Sun Sep 22 10:39:54 2019 kern.info kernel: [ 10.073216] ieee80211 phy1: Atheros AR9300 Rev:4 mem=0xb0000000, irq=40 Sun Sep 22 10:39:54 2019 user.info kernel: [ 10.186159] kmodloader: done loading kernel modules from /etc/modules.d/* Sun Sep 22 10:39:54 2019 kern.warn kernel: [ 11.734884] urandom_read: 5 callbacks suppressed Sun Sep 22 10:39:54 2019 kern.notice kernel: [ 11.734892] random: jshn: uninitialized urandom read (4 bytes read) Sun Sep 22 10:39:54 2019 user.notice dnsmasq: DNS rebinding protection is active, will discard upstream RFC1918 responses! Sun Sep 22 10:39:54 2019 user.notice dnsmasq: Allowing 127.0.0.0/8 responses Sun Sep 22 10:39:55 2019 daemon.info dnsmasq[830]: started, version 2.80 cachesize 150 Sun Sep 22 10:39:55 2019 daemon.info dnsmasq[830]: 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 no-ID loop-detect inotify dumpfile Sun Sep 22 10:39:55 2019 daemon.info dnsmasq[830]: using local addresses only for domain test Sun Sep 22 10:39:55 2019 daemon.info dnsmasq[830]: using local addresses only for domain onion Sun Sep 22 10:39:55 2019 daemon.info dnsmasq[830]: using local addresses only for domain localhost Sun Sep 22 10:39:55 2019 daemon.info dnsmasq[830]: using local addresses only for domain local Sun Sep 22 10:39:55 2019 daemon.info dnsmasq[830]: using local addresses only for domain invalid Sun Sep 22 10:39:55 2019 daemon.info dnsmasq[830]: using local addresses only for domain bind Sun Sep 22 10:39:55 2019 daemon.info dnsmasq[830]: using local addresses only for domain lan Sun Sep 22 10:39:55 2019 daemon.warn dnsmasq[830]: no servers found in /tmp/resolv.conf.auto, will retry Sun Sep 22 10:39:55 2019 daemon.info dnsmasq[830]: read /etc/hosts - 1 addresses Sun Sep 22 10:39:55 2019 daemon.info dnsmasq[830]: read /tmp/hosts/dhcp.cfg01411c - 0 addresses Sun Sep 22 10:39:58 2019 user.notice : Added device handler type: tunnel Sun Sep 22 10:39:58 2019 user.notice : Added device handler type: Network device Sun Sep 22 10:39:58 2019 user.notice : Added device handler type: bridge Sun Sep 22 10:39:58 2019 user.notice : Added device handler type: veth Sun Sep 22 10:39:58 2019 user.notice : Added device handler type: macvlan Sun Sep 22 10:39:58 2019 user.notice : Added device handler type: 8021ad Sun Sep 22 10:39:58 2019 user.notice : Added device handler type: 8021q Sun Sep 22 10:39:59 2019 cron.info crond[970]: crond (busybox 1.28.4) started, log level 8 Sun Sep 22 10:40:01 2019 user.notice ucitrack: Setting up /etc/config/network reload dependency on /etc/config/dhcp Sun Sep 22 10:40:01 2019 user.notice ucitrack: Setting up /etc/config/network reload dependency on /etc/config/radvd Sun Sep 22 10:40:01 2019 user.notice ucitrack: Setting up /etc/config/wireless reload dependency on /etc/config/network Sun Sep 22 10:40:01 2019 user.notice ucitrack: Setting up /etc/config/firewall reload dependency on /etc/config/luci-splash Sun Sep 22 10:40:01 2019 user.notice ucitrack: Setting up /etc/config/firewall reload dependency on /etc/config/qos Sun Sep 22 10:40:01 2019 user.notice ucitrack: Setting up /etc/config/firewall reload dependency on /etc/config/miniupnpd Sun Sep 22 10:40:01 2019 user.notice ucitrack: Setting up /etc/config/dhcp reload dependency on /etc/config/odhcpd Sun Sep 22 10:40:02 2019 user.notice ucitrack: Setting up /etc/config/system reload trigger for non-procd /etc/init.d/led Sun Sep 22 10:40:02 2019 user.notice ucitrack: Setting up /etc/config/system reload dependency on /etc/config/luci_statistics Sun Sep 22 10:40:04 2019 daemon.notice procd: /etc/rc.d/S96led: setting up led USB1 Sun Sep 22 10:40:04 2019 kern.info kernel: [ 23.231335] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready Sun Sep 22 10:40:04 2019 kern.info kernel: [ 23.238592] eth0: link up (1000Mbps/Full duplex) Sun Sep 22 10:40:04 2019 kern.info kernel: [ 23.258184] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready Sun Sep 22 10:40:04 2019 kern.info kernel: [ 23.283887] br-lan: port 1(eth0.1) entered blocking state Sun Sep 22 10:40:04 2019 kern.info kernel: [ 23.289376] br-lan: port 1(eth0.1) entered disabled state Sun Sep 22 10:40:04 2019 kern.info kernel: [ 23.295456] device eth0.1 entered promiscuous mode Sun Sep 22 10:40:04 2019 kern.info kernel: [ 23.300326] device eth0 entered promiscuous mode Sun Sep 22 10:40:04 2019 daemon.notice procd: /etc/rc.d/S96led: setting up led USB2 Sun Sep 22 10:40:04 2019 daemon.notice procd: /etc/rc.d/S96led: setting up led WLAN2G Sun Sep 22 10:40:04 2019 kern.info kernel: [ 23.364645] br-lan: port 1(eth0.1) entered blocking state Sun Sep 22 10:40:04 2019 kern.info kernel: [ 23.370134] br-lan: port 1(eth0.1) entered forwarding state Sun Sep 22 10:40:04 2019 kern.info kernel: [ 23.375998] IPv6: ADDRCONF(NETDEV_UP): br-lan: link is not ready Sun Sep 22 10:40:04 2019 daemon.notice netifd: Interface 'lan' is enabled Sun Sep 22 10:40:04 2019 daemon.notice netifd: Interface 'lan' is setting up now Sun Sep 22 10:40:04 2019 daemon.notice netifd: Interface 'lan' is now up Sun Sep 22 10:40:04 2019 daemon.info dnsmasq[830]: reading /tmp/resolv.conf.auto Sun Sep 22 10:40:04 2019 daemon.info dnsmasq[830]: using local addresses only for domain test Sun Sep 22 10:40:04 2019 daemon.info dnsmasq[830]: using local addresses only for domain onion Sun Sep 22 10:40:04 2019 daemon.info dnsmasq[830]: using local addresses only for domain localhost Sun Sep 22 10:40:04 2019 daemon.info dnsmasq[830]: using local addresses only for domain local Sun Sep 22 10:40:04 2019 daemon.info dnsmasq[830]: using local addresses only for domain invalid Sun Sep 22 10:40:04 2019 daemon.info dnsmasq[830]: using local addresses only for domain bind Sun Sep 22 10:40:04 2019 daemon.info dnsmasq[830]: using local addresses only for domain lan Sun Sep 22 10:40:04 2019 daemon.info dnsmasq[830]: using nameserver 8.8.8.8#53 Sun Sep 22 10:40:04 2019 daemon.notice netifd: Interface 'loopback' is enabled Sun Sep 22 10:40:04 2019 daemon.notice netifd: Interface 'loopback' is setting up now Sun Sep 22 10:40:04 2019 daemon.notice netifd: Interface 'loopback' is now up Sun Sep 22 10:40:04 2019 daemon.notice netifd: Interface 'wan' is enabled Sun Sep 22 10:40:04 2019 daemon.notice netifd: bridge 'br-lan' link is up Sun Sep 22 10:40:04 2019 daemon.notice netifd: Interface 'lan' has link connectivity Sun Sep 22 10:40:04 2019 daemon.notice netifd: Network device 'eth0' link is up Sun Sep 22 10:40:04 2019 daemon.notice netifd: VLAN 'eth0.1' link is up Sun Sep 22 10:40:04 2019 daemon.notice netifd: Network device 'lo' link is up Sun Sep 22 10:40:04 2019 daemon.notice netifd: Interface 'loopback' has link connectivity Sun Sep 22 10:40:04 2019 daemon.notice netifd: VLAN 'eth0.2' link is up Sun Sep 22 10:40:04 2019 daemon.notice netifd: Interface 'wan' has link connectivity Sun Sep 22 10:40:04 2019 daemon.notice netifd: Interface 'wan' is setting up now Sun Sep 22 10:40:05 2019 daemon.err insmod: module is already loaded - slhc Sun Sep 22 10:40:05 2019 daemon.err insmod: module is already loaded - ppp_generic Sun Sep 22 10:40:05 2019 daemon.err insmod: module is already loaded - pppox Sun Sep 22 10:40:05 2019 kern.info kernel: [ 24.271195] IPv6: ADDRCONF(NETDEV_CHANGE): br-lan: link becomes ready Sun Sep 22 10:40:05 2019 daemon.err insmod: module is already loaded - pppoe Sun Sep 22 10:40:05 2019 daemon.info odhcpd[945]: Using a RA lifetime of 1800 seconds on br-lan Sun Sep 22 10:40:05 2019 daemon.notice odhcpd[945]: Failed to send to ff02::1%br-lan (Address not available) Sun Sep 22 10:40:06 2019 user.notice firewall: Reloading firewall due to ifup of lan (br-lan) Sun Sep 22 10:40:07 2019 kern.debug kernel: [ 26.300114] ath: EEPROM regdomain: 0x8282 Sun Sep 22 10:40:07 2019 kern.debug kernel: [ 26.304232] ath: EEPROM indicates we should expect a country code Sun Sep 22 10:40:07 2019 kern.debug kernel: [ 26.310411] ath: doing EEPROM country->regdmn map search Sun Sep 22 10:40:07 2019 kern.debug kernel: [ 26.315818] ath: country maps to regdmn code: 0x3 Sun Sep 22 10:40:07 2019 kern.debug kernel: [ 26.320592] ath: Country alpha2 being used: RO Sun Sep 22 10:40:07 2019 kern.debug kernel: [ 26.325110] ath: Regpair used: 0x3 Sun Sep 22 10:40:07 2019 kern.debug kernel: [ 26.328557] ath: regdomain 0x8282 dynamically updated by user Sun Sep 22 10:40:07 2019 kern.debug kernel: [ 26.334474] ath: EEPROM regdomain: 0x8282 Sun Sep 22 10:40:07 2019 kern.debug kernel: [ 26.338542] ath: EEPROM indicates we should expect a country code Sun Sep 22 10:40:07 2019 kern.debug kernel: [ 26.344739] ath: doing EEPROM country->regdmn map search Sun Sep 22 10:40:07 2019 kern.debug kernel: [ 26.350126] ath: country maps to regdmn code: 0x3 Sun Sep 22 10:40:07 2019 kern.debug kernel: [ 26.354905] ath: Country alpha2 being used: RO Sun Sep 22 10:40:07 2019 kern.debug kernel: [ 26.359407] ath: Regpair used: 0x3 Sun Sep 22 10:40:07 2019 kern.debug kernel: [ 26.362880] ath: regdomain 0x8282 dynamically updated by user Sun Sep 22 10:40:07 2019 user.notice mac80211: Failed command: iw phy phy1 set antenna all all Sun Sep 22 10:40:08 2019 daemon.info pppd[1316]: Plugin rp-pppoe.so loaded. Sun Sep 22 10:40:08 2019 daemon.info pppd[1316]: RP-PPPoE plugin version 3.8p compiled against pppd 2.4.7 Sun Sep 22 10:40:08 2019 daemon.notice pppd[1316]: pppd 2.4.7 started by root, uid 0 Sun Sep 22 10:40:08 2019 daemon.info procd: - init complete - Sun Sep 22 10:40:08 2019 user.notice mac80211: Failed command: iw phy phy0 set antenna all all Sun Sep 22 10:40:08 2019 daemon.info pppd[1316]: PPP session is 3841 Sun Sep 22 10:40:08 2019 daemon.warn pppd[1316]: Connected to 48:f8:db:05:c6:ea via interface eth0.2 Sun Sep 22 10:40:08 2019 kern.info kernel: [ 27.576350] pppoe-wan: renamed from ppp0 Sun Sep 22 10:40:08 2019 daemon.info pppd[1316]: Using interface pppoe-wan Sun Sep 22 10:40:08 2019 daemon.notice pppd[1316]: Connect: pppoe-wan <--> eth0.2 Sun Sep 22 10:40:09 2019 daemon.notice openvpn(airvpn)[1105]: OpenVPN 2.4.5 mips-openwrt-linux-gnu [SSL (OpenSSL)] [LZO] [LZ4] [EPOLL] [MH/PKTINFO] [AEAD] Sun Sep 22 10:40:09 2019 daemon.notice openvpn(airvpn)[1105]: library versions: OpenSSL 1.0.2s 28 May 2019, LZO 2.10 Sun Sep 22 10:40:09 2019 authpriv.info dropbear[1435]: Not backgrounding Sun Sep 22 10:40:10 2019 daemon.notice openvpn(airvpn)[1105]: Outgoing Control Channel Authentication: Using 160 bit message hash 'SHA1' for HMAC authentication Sun Sep 22 10:40:10 2019 daemon.notice openvpn(airvpn)[1105]: Incoming Control Channel Authentication: Using 160 bit message hash 'SHA1' for HMAC authentication Sun Sep 22 10:40:10 2019 daemon.notice openvpn(airvpn)[1105]: TCP/UDP: Preserving recently used remote address: [AF_INET6]2001:ac8:29:5:2a4b:7bb6:475d:f0f4:443 Sun Sep 22 10:40:10 2019 daemon.notice openvpn(airvpn)[1105]: Socket Buffers: R=[163840->163840] S=[163840->163840] Sun Sep 22 10:40:10 2019 daemon.notice openvpn(airvpn)[1105]: UDPv6 link local: (not bound) Sun Sep 22 10:40:10 2019 daemon.notice openvpn(airvpn)[1105]: UDPv6 link remote: [AF_INET6]2001:ac8:29:5:2a4b:7bb6:475d:f0f4:443 Sun Sep 22 10:40:10 2019 daemon.err openvpn(airvpn)[1105]: write UDPv6: Permission denied (code=13) Sun Sep 22 10:40:10 2019 daemon.err hostapd: Configuration file: /var/run/hostapd-phy0.conf Sun Sep 22 10:40:10 2019 kern.info kernel: [ 29.274955] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready Sun Sep 22 10:40:10 2019 kern.info kernel: [ 29.324162] br-lan: port 2(wlan0) entered blocking state Sun Sep 22 10:40:10 2019 kern.info kernel: [ 29.329564] br-lan: port 2(wlan0) entered disabled state Sun Sep 22 10:40:10 2019 kern.info kernel: [ 29.335426] device wlan0 entered promiscuous mode Sun Sep 22 10:40:10 2019 daemon.notice hostapd: wlan0: interface state UNINITIALIZED->COUNTRY_UPDATE Sun Sep 22 10:40:10 2019 daemon.err hostapd: Using interface wlan0 with hwaddr c4:e9:84:91:10:50 and ssid "The Zone" Sun Sep 22 10:40:11 2019 kern.info kernel: [ 29.877603] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready Sun Sep 22 10:40:11 2019 kern.info kernel: [ 29.884281] br-lan: port 2(wlan0) entered blocking state Sun Sep 22 10:40:11 2019 kern.info kernel: [ 29.889684] br-lan: port 2(wlan0) entered forwarding state Sun Sep 22 10:40:11 2019 daemon.notice hostapd: wlan0: interface state COUNTRY_UPDATE->ENABLED Sun Sep 22 10:40:11 2019 daemon.notice hostapd: wlan0: AP-ENABLED Sun Sep 22 10:40:11 2019 daemon.notice netifd: Network device 'wlan0' link is up Sun Sep 22 10:40:12 2019 daemon.notice pppd[1316]: PAP authentication succeeded Sun Sep 22 10:40:12 2019 daemon.notice pppd[1316]: peer from calling number 48:F8:DB:05:C6:EA authorized Sun Sep 22 10:40:12 2019 daemon.notice pppd[1316]: local LL address fe80::0000:0000:567c:bc92 Sun Sep 22 10:40:12 2019 daemon.notice pppd[1316]: remote LL address fe80::0000:0000:0000:0001 Sun Sep 22 10:40:12 2019 daemon.notice pppd[1316]: local IP address 86.124.188.146 Sun Sep 22 10:40:12 2019 daemon.notice pppd[1316]: remote IP address 10.0.0.1 Sun Sep 22 10:40:12 2019 daemon.notice pppd[1316]: primary DNS address 193.231.252.1 Sun Sep 22 10:40:12 2019 daemon.notice pppd[1316]: secondary DNS address 213.154.124.1 Sun Sep 22 10:40:12 2019 daemon.notice netifd: Network device 'pppoe-wan' link is up Sun Sep 22 10:40:12 2019 daemon.notice netifd: Interface 'wan' is now up Sun Sep 22 10:40:12 2019 daemon.info dnsmasq[830]: reading /tmp/resolv.conf.auto Sun Sep 22 10:40:12 2019 daemon.info dnsmasq[830]: using local addresses only for domain test Sun Sep 22 10:40:12 2019 daemon.info dnsmasq[830]: using local addresses only for domain onion Sun Sep 22 10:40:12 2019 daemon.info dnsmasq[830]: using local addresses only for domain localhost Sun Sep 22 10:40:12 2019 daemon.info dnsmasq[830]: using local addresses only for domain local Sun Sep 22 10:40:12 2019 daemon.info dnsmasq[830]: using local addresses only for domain invalid Sun Sep 22 10:40:12 2019 daemon.info dnsmasq[830]: using local addresses only for domain bind Sun Sep 22 10:40:12 2019 daemon.info dnsmasq[830]: using local addresses only for domain lan Sun Sep 22 10:40:12 2019 daemon.info dnsmasq[830]: using nameserver 8.8.8.8#53 Sun Sep 22 10:40:12 2019 daemon.info dnsmasq[830]: using nameserver 8.8.8.8#53 Sun Sep 22 10:40:12 2019 daemon.notice netifd: Network alias 'pppoe-wan' link is up Sun Sep 22 10:40:12 2019 daemon.notice netifd: Interface 'wan_6' is enabled Sun Sep 22 10:40:12 2019 daemon.notice netifd: Interface 'wan_6' has link connectivity Sun Sep 22 10:40:12 2019 daemon.notice netifd: Interface 'wan_6' is setting up now Sun Sep 22 10:40:12 2019 daemon.err openvpn(airvpn)[1105]: write UDPv6: Permission denied (code=13) Sun Sep 22 10:40:12 2019 user.notice firewall: Reloading firewall due to ifup of wan (pppoe-wan) Sun Sep 22 10:40:13 2019 daemon.info hostapd: wlan0: STA 94:65:9c:10:bd:a0 IEEE 802.11: authenticated Sun Sep 22 10:40:13 2019 daemon.info hostapd: wlan0: STA 94:65:9c:10:bd:a0 IEEE 802.11: associated (aid 1) Sun Sep 22 10:40:13 2019 daemon.notice hostapd: wlan0: AP-STA-CONNECTED 94:65:9c:10:bd:a0 Sun Sep 22 10:40:13 2019 daemon.info hostapd: wlan0: STA 94:65:9c:10:bd:a0 WPA: pairwise key handshake completed (RSN) Sun Sep 22 10:40:13 2019 daemon.info odhcpd[945]: Using a RA lifetime of 1800 seconds on br-lan Sun Sep 22 10:40:13 2019 user.notice firewall: Reloading firewall due to ifupdate of wan (pppoe-wan) Sun Sep 22 10:40:14 2019 daemon.info dnsmasq[830]: exiting on receipt of SIGTERM Sun Sep 22 10:40:14 2019 daemon.info dnsmasq[1678]: started, version 2.80 cachesize 150 Sun Sep 22 10:40:14 2019 daemon.info dnsmasq[1678]: 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 no-ID loop-detect inotify dumpfile Sun Sep 22 10:40:14 2019 daemon.info dnsmasq-dhcp[1678]: DHCP, IP range 192.168.1.100 -- 192.168.1.249, lease time 12h Sun Sep 22 10:40:14 2019 daemon.info dnsmasq[1678]: using local addresses only for domain test Sun Sep 22 10:40:14 2019 daemon.info dnsmasq[1678]: using local addresses only for domain onion Sun Sep 22 10:40:14 2019 daemon.info dnsmasq[1678]: using local addresses only for domain localhost Sun Sep 22 10:40:14 2019 daemon.info dnsmasq[1678]: using local addresses only for domain local Sun Sep 22 10:40:14 2019 daemon.info dnsmasq[1678]: using local addresses only for domain invalid Sun Sep 22 10:40:14 2019 daemon.info dnsmasq[1678]: using local addresses only for domain bind Sun Sep 22 10:40:14 2019 daemon.info dnsmasq[1678]: using local addresses only for domain lan Sun Sep 22 10:40:14 2019 daemon.info dnsmasq[1678]: reading /tmp/resolv.conf.auto Sun Sep 22 10:40:14 2019 daemon.info dnsmasq[1678]: using local addresses only for domain test Sun Sep 22 10:40:14 2019 daemon.info dnsmasq[1678]: using local addresses only for domain onion Sun Sep 22 10:40:14 2019 daemon.info dnsmasq[1678]: using local addresses only for domain localhost Sun Sep 22 10:40:14 2019 daemon.info dnsmasq[1678]: using local addresses only for domain local Sun Sep 22 10:40:14 2019 daemon.info dnsmasq[1678]: using local addresses only for domain invalid Sun Sep 22 10:40:14 2019 daemon.info dnsmasq[1678]: using local addresses only for domain bind Sun Sep 22 10:40:14 2019 daemon.info dnsmasq[1678]: using local addresses only for domain lan Sun Sep 22 10:40:14 2019 daemon.info dnsmasq[1678]: using nameserver 8.8.8.8#53 Sun Sep 22 10:40:14 2019 daemon.info dnsmasq[1678]: using nameserver 8.8.8.8#53 Sun Sep 22 10:40:14 2019 daemon.info dnsmasq[1678]: read /etc/hosts - 1 addresses Sun Sep 22 10:40:14 2019 daemon.info dnsmasq[1678]: read /tmp/hosts/dhcp.cfg01411c - 2 addresses Sun Sep 22 10:40:14 2019 daemon.info dnsmasq-dhcp[1678]: read /etc/ethers - 0 addresses Sun Sep 22 10:40:14 2019 daemon.info dnsmasq[1678]: read /etc/hosts - 1 addresses Sun Sep 22 10:40:14 2019 daemon.info dnsmasq[1678]: read /tmp/hosts/dhcp.cfg01411c - 2 addresses Sun Sep 22 10:40:14 2019 daemon.info dnsmasq-dhcp[1678]: read /etc/ethers - 0 addresses Sun Sep 22 10:40:14 2019 daemon.info odhcpd[945]: Using a RA lifetime of 1800 seconds on br-lan Sun Sep 22 10:40:15 2019 daemon.notice odhcpd[945]: Got DHCPv6 request Sun Sep 22 10:40:16 2019 daemon.err openvpn(airvpn)[1105]: write UDPv6: Permission denied (code=13) Sun Sep 22 10:40:41 2019 daemon.err openvpn(airvpn)[1105]: write UDPv6: Permission denied (code=13) Sun Sep 22 10:40:42 2019 daemon.info dnsmasq-dhcp[1678]: DHCPREQUEST(br-lan) 192.168.1.244 94:65:9c:10:bd:a0 Sun Sep 22 10:40:42 2019 daemon.info dnsmasq-dhcp[1678]: DHCPACK(br-lan) 192.168.1.244 94:65:9c:10:bd:a0 ultimate Sun Sep 22 10:40:42 2019 authpriv.info dropbear[1726]: Child connection from 192.168.1.244:56166 Sun Sep 22 10:40:47 2019 authpriv.notice dropbear[1726]: Password auth succeeded for 'root' from 192.168.1.244:56166 Sun Sep 22 10:40:48 2019 daemon.notice netifd: Interface 'wan_6' is now up Sun Sep 22 10:40:48 2019 daemon.info dnsmasq[1678]: reading /tmp/resolv.conf.auto Sun Sep 22 10:40:48 2019 daemon.info dnsmasq[1678]: using local addresses only for domain test Sun Sep 22 10:40:48 2019 daemon.info dnsmasq[1678]: using local addresses only for domain onion Sun Sep 22 10:40:48 2019 daemon.info dnsmasq[1678]: using local addresses only for domain localhost Sun Sep 22 10:40:48 2019 daemon.info dnsmasq[1678]: using local addresses only for domain local Sun Sep 22 10:40:48 2019 daemon.info dnsmasq[1678]: using local addresses only for domain invalid Sun Sep 22 10:40:48 2019 daemon.info dnsmasq[1678]: using local addresses only for domain bind Sun Sep 22 10:40:48 2019 daemon.info dnsmasq[1678]: using local addresses only for domain lan Sun Sep 22 10:40:48 2019 daemon.info dnsmasq[1678]: using nameserver 8.8.8.8#53 Sun Sep 22 10:40:48 2019 daemon.info dnsmasq[1678]: using nameserver 8.8.8.8#53 Sun Sep 22 10:40:48 2019 user.notice firewall: Reloading firewall due to ifup of wan_6 (pppoe-wan) Sun Sep 22 10:40:49 2019 daemon.info odhcpd[945]: Using a RA lifetime of 1800 seconds on br-lan Sun Sep 22 10:40:50 2019 daemon.info dnsmasq[1678]: read /etc/hosts - 1 addresses Sun Sep 22 10:40:50 2019 daemon.info dnsmasq[1678]: read /tmp/hosts/odhcpd - 0 addresses Sun Sep 22 10:40:50 2019 daemon.info dnsmasq[1678]: read /tmp/hosts/dhcp.cfg01411c - 2 addresses Sun Sep 22 10:40:50 2019 daemon.info dnsmasq-dhcp[1678]: read /etc/ethers - 0 addresses Sun Sep 22 10:40:50 2019 daemon.info odhcpd[945]: Using a RA lifetime of 1800 seconds on br-lan Sun Sep 22 10:40:57 2019 daemon.notice openvpn(airvpn)[1105]: TLS: Initial packet from [AF_INET6]2001:ac8:29:5:2a4b:7bb6:475d:f0f4:443, sid=16fd26ee 5e4b1644 Sun Sep 22 10:40:58 2019 daemon.notice openvpn(airvpn)[1105]: VERIFY OK: depth=1, C=IT, ST=IT, L=Perugia, O=airvpn.org, CN=airvpn.org CA, emailAddress=info@airvpn.org Sun Sep 22 10:40:58 2019 daemon.notice openvpn(airvpn)[1105]: VERIFY KU OK Sun Sep 22 10:40:58 2019 daemon.notice openvpn(airvpn)[1105]: Validating certificate extended key usage Sun Sep 22 10:40:58 2019 daemon.notice openvpn(airvpn)[1105]: ++ Certificate has EKU (str) TLS Web Server Authentication, expects TLS Web Server Authentication Sun Sep 22 10:40:58 2019 daemon.notice openvpn(airvpn)[1105]: VERIFY EKU OK Sun Sep 22 10:40:58 2019 daemon.notice openvpn(airvpn)[1105]: VERIFY OK: depth=0, C=IT, ST=IT, L=Perugia, O=airvpn.org, CN=Alderamin, emailAddress=info@airvpn.org Sun Sep 22 10:41:04 2019 daemon.notice openvpn(airvpn)[1105]: Control Channel: TLSv1.2, cipher TLSv1/SSLv3 DHE-RSA-AES256-GCM-SHA384, 4096 bit RSA Sun Sep 22 10:41:04 2019 daemon.notice openvpn(airvpn)[1105]: [Alderamin] Peer Connection Initiated with [AF_INET6]2001:ac8:29:5:2a4b:7bb6:475d:f0f4:443 Sun Sep 22 10:41:05 2019 daemon.notice openvpn(airvpn)[1105]: SENT CONTROL [Alderamin]: 'PUSH_REQUEST' (status=1) Sun Sep 22 10:41:05 2019 daemon.notice openvpn(airvpn)[1105]: PUSH: Received control message: 'PUSH_REPLY,comp-lzo no,redirect-gateway ipv6 def1 bypass-dhcp,dhcp-option DNS 10.5.224.1,dhcp-option DNS6 fde6:7a:7d20:1e0::1,tun-ipv6,route-gateway 10.5.224.1,topology subnet,ping 10,ping-restart 60,ifconfig-ipv6 fde6:7a:7d20:1e0::1025/64 fde6:7a:7d20:1e0::1,ifconfig 10.5.224.39 255.255.255.0,peer-id 2,cipher AES-256-GCM' Sun Sep 22 10:41:05 2019 daemon.notice openvpn(airvpn)[1105]: OPTIONS IMPORT: timers and/or timeouts modified Sun Sep 22 10:41:05 2019 daemon.notice openvpn(airvpn)[1105]: OPTIONS IMPORT: compression parms modified Sun Sep 22 10:41:05 2019 daemon.notice openvpn(airvpn)[1105]: OPTIONS IMPORT: --ifconfig/up options modified Sun Sep 22 10:41:05 2019 daemon.notice openvpn(airvpn)[1105]: OPTIONS IMPORT: route options modified Sun Sep 22 10:41:05 2019 daemon.notice openvpn(airvpn)[1105]: OPTIONS IMPORT: route-related options modified Sun Sep 22 10:41:05 2019 daemon.notice openvpn(airvpn)[1105]: OPTIONS IMPORT: --ip-win32 and/or --dhcp-option options modified Sun Sep 22 10:41:05 2019 daemon.notice openvpn(airvpn)[1105]: OPTIONS IMPORT: peer-id set Sun Sep 22 10:41:05 2019 daemon.notice openvpn(airvpn)[1105]: OPTIONS IMPORT: adjusting link_mtu to 1625 Sun Sep 22 10:41:05 2019 daemon.notice openvpn(airvpn)[1105]: OPTIONS IMPORT: data channel crypto options modified Sun Sep 22 10:41:05 2019 daemon.notice openvpn(airvpn)[1105]: Data Channel: using negotiated cipher 'AES-256-GCM' Sun Sep 22 10:41:05 2019 daemon.notice openvpn(airvpn)[1105]: Outgoing Data Channel: Cipher 'AES-256-GCM' initialized with 256 bit key Sun Sep 22 10:41:05 2019 daemon.notice openvpn(airvpn)[1105]: Incoming Data Channel: Cipher 'AES-256-GCM' initialized with 256 bit key Sun Sep 22 10:41:05 2019 daemon.notice openvpn(airvpn)[1105]: GDG6: remote_host_ipv6=2001:ac8:29:5:2a4b:7bb6:475d:f0f4 Sun Sep 22 10:41:05 2019 daemon.notice openvpn(airvpn)[1105]: ROUTE6: 2000::/4 overlaps IPv6 remote 2001:ac8:29:5:2a4b:7bb6:475d:f0f4, adding host route to VPN endpoint Sun Sep 22 10:41:05 2019 daemon.warn openvpn(airvpn)[1105]: ROUTE6: IPv6 route overlaps with IPv6 remote address, but could not determine IPv6 gateway address + interface, expect failure Sun Sep 22 10:41:05 2019 daemon.notice netifd: Interface 'AirVPN' is enabled Sun Sep 22 10:41:05 2019 daemon.notice netifd: Network device 'tun0' link is up Sun Sep 22 10:41:05 2019 daemon.notice netifd: Interface 'AirVPN' has link connectivity Sun Sep 22 10:41:05 2019 daemon.notice netifd: Interface 'AirVPN' is setting up now Sun Sep 22 10:41:05 2019 daemon.notice netifd: Interface 'AirVPN' is now up Sun Sep 22 10:41:05 2019 daemon.notice openvpn(airvpn)[1105]: TUN/TAP device tun0 opened Sun Sep 22 10:41:05 2019 daemon.notice openvpn(airvpn)[1105]: TUN/TAP TX queue length set to 100 Sun Sep 22 10:41:05 2019 daemon.notice openvpn(airvpn)[1105]: do_ifconfig, tt->did_ifconfig_ipv6_setup=1 Sun Sep 22 10:41:05 2019 daemon.notice openvpn(airvpn)[1105]: /sbin/ifconfig tun0 10.5.224.39 netmask 255.255.255.0 mtu 1500 broadcast 10.5.224.255 Sun Sep 22 10:41:05 2019 daemon.notice openvpn(airvpn)[1105]: /sbin/ifconfig tun0 add fde6:7a:7d20:1e0::1025/64 Sun Sep 22 10:41:05 2019 user.notice firewall: Reloading firewall due to ifup of AirVPN (tun0) Sun Sep 22 10:41:06 2019 daemon.info odhcpd[945]: Using a RA lifetime of 1800 seconds on br-lan Sun Sep 22 10:41:10 2019 daemon.notice openvpn(airvpn)[1105]: /sbin/route add -net 0.0.0.0 netmask 128.0.0.0 gw 10.5.224.1 Sun Sep 22 10:41:10 2019 daemon.notice openvpn(airvpn)[1105]: /sbin/route add -net 128.0.0.0 netmask 128.0.0.0 gw 10.5.224.1 Sun Sep 22 10:41:10 2019 daemon.notice openvpn(airvpn)[1105]: add_route_ipv6(::/3 -> fde6:7a:7d20:1e0::1 metric -1) dev tun0 Sun Sep 22 10:41:10 2019 daemon.notice openvpn(airvpn)[1105]: /sbin/route -A inet6 add ::/3 dev tun0 Sun Sep 22 10:41:10 2019 daemon.notice openvpn(airvpn)[1105]: add_route_ipv6(2000::/4 -> fde6:7a:7d20:1e0::1 metric -1) dev tun0 Sun Sep 22 10:41:10 2019 daemon.notice openvpn(airvpn)[1105]: /sbin/route -A inet6 add 2000::/4 dev tun0 Sun Sep 22 10:41:10 2019 daemon.notice openvpn(airvpn)[1105]: add_route_ipv6(3000::/4 -> fde6:7a:7d20:1e0::1 metric -1) dev tun0 Sun Sep 22 10:41:10 2019 daemon.notice openvpn(airvpn)[1105]: /sbin/route -A inet6 add 3000::/4 dev tun0 Sun Sep 22 10:41:10 2019 daemon.notice openvpn(airvpn)[1105]: add_route_ipv6(fc00::/7 -> fde6:7a:7d20:1e0::1 metric -1) dev tun0 Sun Sep 22 10:41:10 2019 daemon.notice openvpn(airvpn)[1105]: /sbin/route -A inet6 add fc00::/7 dev tun0 Sun Sep 22 10:41:10 2019 daemon.notice openvpn(airvpn)[1105]: Initialization Sequence Completed Sun Sep 22 10:41:11 2019 kern.notice kernel: [ 66.500922] random: crng init done Sun Sep 22 10:41:15 2019 daemon.info pppd[1316]: System time change detected. Sun Sep 22 10:41:40 2019 daemon.info dnsmasq-dhcp[1678]: DHCPDISCOVER(br-lan) 192.168.1.223 3c:2a:f4:a2:6a:7d Sun Sep 22 10:41:40 2019 daemon.info dnsmasq-dhcp[1678]: DHCPOFFER(br-lan) 192.168.1.223 3c:2a:f4:a2:6a:7d Sun Sep 22 10:42:44 2019 daemon.info dnsmasq-dhcp[1678]: DHCPDISCOVER(br-lan) 192.168.1.223 3c:2a:f4:a2:6a:7d Sun Sep 22 10:42:44 2019 daemon.info dnsmasq-dhcp[1678]: DHCPOFFER(br-lan) 192.168.1.223 3c:2a:f4:a2:6a:7d Sun Sep 22 10:43:11 2019 daemon.info hostapd: wlan0: STA 94:65:2d:51:b8:e8 IEEE 802.11: authenticated Sun Sep 22 10:43:11 2019 daemon.info hostapd: wlan0: STA 94:65:2d:51:b8:e8 IEEE 802.11: associated (aid 2) Sun Sep 22 10:43:11 2019 daemon.notice hostapd: wlan0: AP-STA-CONNECTED 94:65:2d:51:b8:e8 Sun Sep 22 10:43:11 2019 daemon.info hostapd: wlan0: STA 94:65:2d:51:b8:e8 WPA: pairwise key handshake completed (RSN) Sun Sep 22 10:43:12 2019 daemon.info odhcpd[945]: Using a RA lifetime of 1800 seconds on br-lan Sun Sep 22 10:43:15 2019 daemon.info dnsmasq-dhcp[1678]: DHCPDISCOVER(br-lan) 94:65:2d:51:b8:e8 Sun Sep 22 10:43:15 2019 daemon.info dnsmasq-dhcp[1678]: DHCPOFFER(br-lan) 192.168.1.173 94:65:2d:51:b8:e8 Sun Sep 22 10:43:15 2019 daemon.info dnsmasq-dhcp[1678]: DHCPDISCOVER(br-lan) 94:65:2d:51:b8:e8 Sun Sep 22 10:43:15 2019 daemon.info dnsmasq-dhcp[1678]: DHCPOFFER(br-lan) 192.168.1.173 94:65:2d:51:b8:e8 Sun Sep 22 10:43:15 2019 daemon.info dnsmasq-dhcp[1678]: DHCPREQUEST(br-lan) 192.168.1.173 94:65:2d:51:b8:e8 Sun Sep 22 10:43:15 2019 daemon.info dnsmasq-dhcp[1678]: DHCPACK(br-lan) 192.168.1.173 94:65:2d:51:b8:e8 Sun Sep 22 10:43:49 2019 daemon.info dnsmasq-dhcp[1678]: DHCPDISCOVER(br-lan) 192.168.1.223 3c:2a:f4:a2:6a:7d Sun Sep 22 10:43:49 2019 daemon.info dnsmasq-dhcp[1678]: DHCPOFFER(br-lan) 192.168.1.223 3c:2a:f4:a2:6a:7d Sun Sep 22 10:43:58 2019 daemon.notice openvpn(airvpn)[1105]: [Alderamin] Inactivity timeout (--ping-restart), restarting Sun Sep 22 10:43:58 2019 daemon.notice openvpn(airvpn)[1105]: SIGUSR1[soft,ping-restart] received, process restarting Sun Sep 22 10:43:58 2019 daemon.notice openvpn(airvpn)[1105]: Restart pause, 5 second(s) Sun Sep 22 10:44:03 2019 daemon.notice openvpn(airvpn)[1105]: TCP/UDP: Preserving recently used remote address: [AF_INET6]2001:ac8:29:5:2a4b:7bb6:475d:f0f4:443 Sun Sep 22 10:44:03 2019 daemon.notice openvpn(airvpn)[1105]: Socket Buffers: R=[163840->163840] S=[163840->163840] Sun Sep 22 10:44:03 2019 daemon.notice openvpn(airvpn)[1105]: UDPv6 link local: (not bound) Sun Sep 22 10:44:03 2019 daemon.notice openvpn(airvpn)[1105]: UDPv6 link remote: [AF_INET6]2001:ac8:29:5:2a4b:7bb6:475d:f0f4:443 Sun Sep 22 10:44:40 2019 daemon.notice hostapd: wlan0: AP-STA-DISCONNECTED 94:65:2d:51:b8:e8 Sun Sep 22 10:44:54 2019 daemon.info dnsmasq-dhcp[1678]: DHCPDISCOVER(br-lan) 192.168.1.223 3c:2a:f4:a2:6a:7d Sun Sep 22 10:44:54 2019 daemon.info dnsmasq-dhcp[1678]: DHCPOFFER(br-lan) 192.168.1.223 3c:2a:f4:a2:6a:7d Sun Sep 22 10:45:03 2019 daemon.notice openvpn(airvpn)[1105]: [UNDEF] Inactivity timeout (--ping-restart), restarting Sun Sep 22 10:45:03 2019 daemon.notice openvpn(airvpn)[1105]: SIGUSR1[soft,ping-restart] received, process restarting Sun Sep 22 10:45:03 2019 daemon.notice openvpn(airvpn)[1105]: Restart pause, 5 second(s) Sun Sep 22 10:45:08 2019 daemon.notice openvpn(airvpn)[1105]: TCP/UDP: Preserving recently used remote address: [AF_INET6]2001:ac8:29:5:2a4b:7bb6:475d:f0f4:443 Sun Sep 22 10:45:08 2019 daemon.notice openvpn(airvpn)[1105]: Socket Buffers: R=[163840->163840] S=[163840->163840] Sun Sep 22 10:45:08 2019 daemon.notice openvpn(airvpn)[1105]: UDPv6 link local: (not bound) Sun Sep 22 10:45:08 2019 daemon.notice openvpn(airvpn)[1105]: UDPv6 link remote: [AF_INET6]2001:ac8:29:5:2a4b:7bb6:475d:f0f4:443 Sun Sep 22 10:45:34 2019 daemon.info odhcpd[945]: Using a RA lifetime of 1800 seconds on br-lan Sun Sep 22 10:45:59 2019 daemon.info dnsmasq-dhcp[1678]: DHCPDISCOVER(br-lan) 192.168.1.223 3c:2a:f4:a2:6a:7d Sun Sep 22 10:45:59 2019 daemon.info dnsmasq-dhcp[1678]: DHCPOFFER(br-lan) 192.168.1.223 3c:2a:f4:a2:6a:7d Sun Sep 22 10:46:08 2019 daemon.notice openvpn(airvpn)[1105]: [UNDEF] Inactivity timeout (--ping-restart), restarting Sun Sep 22 10:46:08 2019 daemon.notice openvpn(airvpn)[1105]: SIGUSR1[soft,ping-restart] received, process restarting Sun Sep 22 10:46:08 2019 daemon.notice openvpn(airvpn)[1105]: Restart pause, 5 second(s) Sun Sep 22 10:46:13 2019 daemon.notice openvpn(airvpn)[1105]: TCP/UDP: Preserving recently used remote address: [AF_INET6]2001:ac8:29:5:2a4b:7bb6:475d:f0f4:443 Sun Sep 22 10:46:13 2019 daemon.notice openvpn(airvpn)[1105]: Socket Buffers: R=[163840->163840] S=[163840->163840] Sun Sep 22 10:46:13 2019 daemon.notice openvpn(airvpn)[1105]: UDPv6 link local: (not bound) Sun Sep 22 10:46:13 2019 daemon.notice openvpn(airvpn)[1105]: UDPv6 link remote: [AF_INET6]2001:ac8:29:5:2a4b:7bb6:475d:f0f4:443 Sun Sep 22 10:47:03 2019 daemon.info dnsmasq-dhcp[1678]: DHCPDISCOVER(br-lan) 192.168.1.223 3c:2a:f4:a2:6a:7d Sun Sep 22 10:47:03 2019 daemon.info dnsmasq-dhcp[1678]: DHCPOFFER(br-lan) 192.168.1.223 3c:2a:f4:a2:6a:7d Sun Sep 22 10:47:13 2019 daemon.notice openvpn(airvpn)[1105]: [UNDEF] Inactivity timeout (--ping-restart), restarting Sun Sep 22 10:47:13 2019 daemon.notice openvpn(airvpn)[1105]: SIGUSR1[soft,ping-restart] received, process restarting Sun Sep 22 10:47:13 2019 daemon.notice openvpn(airvpn)[1105]: Restart pause, 5 second(s) Sun Sep 22 10:47:18 2019 daemon.notice openvpn(airvpn)[1105]: TCP/UDP: Preserving recently used remote address: [AF_INET6]2001:ac8:29:5:2a4b:7bb6:475d:f0f4:443 Sun Sep 22 10:47:18 2019 daemon.notice openvpn(airvpn)[1105]: Socket Buffers: R=[163840->163840] S=[163840->163840] Sun Sep 22 10:47:18 2019 daemon.notice openvpn(airvpn)[1105]: UDPv6 link local: (not bound) Sun Sep 22 10:47:18 2019 daemon.notice openvpn(airvpn)[1105]: UDPv6 link remote: [AF_INET6]2001:ac8:29:5:2a4b:7bb6:475d:f0f4:443 Sun Sep 22 10:48:08 2019 daemon.info dnsmasq-dhcp[1678]: DHCPDISCOVER(br-lan) 192.168.1.223 3c:2a:f4:a2:6a:7d Sun Sep 22 10:48:08 2019 daemon.info dnsmasq-dhcp[1678]: DHCPOFFER(br-lan) 192.168.1.223 3c:2a:f4:a2:6a:7d Sun Sep 22 10:48:18 2019 daemon.notice openvpn(airvpn)[1105]: [UNDEF] Inactivity timeout (--ping-restart), restarting Sun Sep 22 10:48:18 2019 daemon.notice openvpn(airvpn)[1105]: SIGUSR1[soft,ping-restart] received, process restarting Sun Sep 22 10:48:18 2019 daemon.notice openvpn(airvpn)[1105]: Restart pause, 5 second(s) Sun Sep 22 10:48:23 2019 daemon.notice openvpn(airvpn)[1105]: TCP/UDP: Preserving recently used remote address: [AF_INET6]2001:ac8:29:5:2a4b:7bb6:475d:f0f4:443 Sun Sep 22 10:48:23 2019 daemon.notice openvpn(airvpn)[1105]: Socket Buffers: R=[163840->163840] S=[163840->163840] Sun Sep 22 10:48:23 2019 daemon.notice openvpn(airvpn)[1105]: UDPv6 link local: (not bound) Sun Sep 22 10:48:23 2019 daemon.notice openvpn(airvpn)[1105]: UDPv6 link remote: [AF_INET6]2001:ac8:29:5:2a4b:7bb6:475d:f0f4:443