OpenWrt/LEDE Project

  • Status Unconfirmed
  • Percent Complete
    0%
  • Task Type Bug Report
  • Category Base system
  • Assigned To No-one
  • Operating System All
  • Severity Low
  • Priority Very Low
  • Reported Version Trunk
  • Due in Version Undecided
  • Due Date Undecided
  • Private
Attached to Project: OpenWrt/LEDE Project
Opened by Brian J. Murrell - 13.01.2020

FS#2732 - openvpn initscript does not start enabled connection on boot

Supply the following if possible:
- Device problem occurs on
Archer C7 V2
- Software versions of OpenWrt/LEDE release, packages, etc.
19.07.0-rc2
- Steps to reproduce

# cat > /etc/config/openvpn
config openvpn 'ilinx'
	option nobind '1'
	option float '1'
	option client '1'
	option comp_lzo 'yes'
	option reneg_sec '0'
	option dev 'tun'
	option verb '3'
	option persist_tun '1'
	option persist_key '1'
	option auth_user_pass '/etc/openvpn/userpass'
	option remote 'openvpn.example.com 1194'
	option ca '/etc/openvpn/ca.crt'
	option enabled '1'
# cat > /etc/openvpn/userpass
...
# /etc/init.d/openvpn enable

Reboot router. Notice that the ilinx openvpn connection is not up.

# ps wwwww
  PID USER       VSZ STAT COMMAND
    1 root      1564 S    /sbin/procd
    2 root         0 SW   [kthreadd]
    3 root         0 IW   [kworker/0:0]
    4 root         0 IW<  [kworker/0:0H]
    6 root         0 IW<  [mm_percpu_wq]
    7 root         0 SW   [ksoftirqd/0]
    8 root         0 IW   [kworker/u2:1]
   98 root         0 SW   [oom_reaper]
   99 root         0 IW<  [writeback]
  101 root         0 SW   [kcompactd0]
  102 root         0 IW<  [crypto]
  104 root         0 IW<  [kblockd]
  119 root         0 IW<  [watchdogd]
  132 root         0 IW   [kworker/0:1]
  140 root         0 SW   [kswapd0]
  198 root         0 SW   [spi0]
  331 root         0 IW   [kworker/u2:2]
  341 root         0 IW<  [ipv6_addrconf]
  378 root         0 IW<  [kworker/0:1H]
  388 root         0 SW   [irq/16-keys]
  389 root         0 SW   [irq/23-keys]
  489 root         0 SWN  [jffs2_gcd_mtd4]
  553 root      1228 S    /sbin/ubusd
  554 root       924 S    /sbin/askfirst /usr/libexec/login.sh
  571 root      1028 S    /sbin/urngd
  645 root         0 IW<  [cfg80211]
  658 root         0 IW<  [ath10k_wq]
  659 root         0 IW<  [ath10k_aux_wq]
  990 root      1248 S    /sbin/logd -S 64
 1020 root      2164 S    /sbin/rpcd -s /var/run/ubus.sock -t 30
 1112 root      1080 S    /usr/sbin/dropbear -F -P /var/run/dropbear.1.pid -p 22 -K 300 -T 3
 1169 root      1748 S    /sbin/netifd
 1279 root      1444 S    /bin/sh /usr/bin/travelmate.sh
 1362 root      1456 S    /usr/sbin/odhcpd
 1414 root      1212 S    udhcpc -p /var/run/udhcpc-eth0.2.pid -s /lib/netifd/dhcp.script -f -t 0 -i eth0.2 -x hostname:ilinx-roam -C -O 121
 1415 root      1052 S    odhcp6c -s /lib/netifd/dhcpv6.script -P0 -t120 eth0.2
 1602 root      1108 S    /usr/bin/netserver
 1649 root      1340 S    /usr/sbin/uhttpd -f -h /www -r ilinx-roam -x /cgi-bin -t 60 -T 30 -k 20 -A 1 -n 3 -N 100 -R -p 0.0.0.0:80 -p [::]:80
 1820 root      1776 S    /usr/sbin/hostapd -s -P /var/run/wifi-phy0.pid -B /var/run/hostapd-phy0.conf
 1994 dnsmasq   1344 S    /usr/sbin/dnsmasq -C /var/etc/dnsmasq.conf.cfg01411c -k -x /var/run/dnsmasq/dnsmasq.cfg01411c.pid
 2147 root      1800 S    /usr/sbin/wpa_supplicant -B -s -P /var/run/wpa_supplicant-wlan1.pid -D nl80211 -i wlan1 -c /var/run/wpa_supplicant-wlan1.conf -C /var/run/wpa_supplicant
 2166 root      1212 S    udhcpc -p /var/run/udhcpc-wlan1.pid -s /lib/netifd/dhcp.script -f -t 0 -i wlan1 -x hostname:ilinx-roam -C -O 121
 2295 root      1216 S<   /usr/sbin/ntpd -n -N -S /usr/sbin/ntpd-hotplug -p 0.openwrt.pool.ntp.org -p 1.openwrt.pool.ntp.org -p 2.openwrt.pool.ntp.org -p 3.openwrt.pool.ntp.org -p 10.75.22.247
14485 root      1148 S    /usr/sbin/dropbear -F -P /var/run/dropbear.1.pid -p 22 -K 300 -T 3
14486 root      1220 S    -ash
15262 root      1148 S    /usr/sbin/dropbear -F -P /var/run/dropbear.1.pid -p 22 -K 300 -T 3
15263 root      1220 S    -ash
16072 root      1284 S    sleep 60 0
16074 root      1212 R    ps wwwww

But openvpn is not running. Yet:

# ls /etc/rc.d/*openvpn
/etc/rc.d/K10openvpn  /etc/rc.d/S90openvpn

Shell xtrace from /etc/init.d/openvpn on boot:

# cat /tmp/openvpn--.debug 
+ START=90
+ STOP=10
+ USE_PROCD=1
+ PROG=/usr/sbin/openvpn
+ LIST_SEP='
'
+ UCI_STARTED=
+ UCI_DISABLED=
+ '[' -n 1 ]
+ EXTRA_COMMANDS=' running trace'
+ . /lib/functions/procd.sh
+ . /usr/share/libubox/jshn.sh
+ PROCD_RELOAD_DELAY=1000
+ _PROCD_SERVICE=
+ _procd_wrapper procd_open_service procd_close_service procd_add_instance procd_add_raw_trigger procd_add_config_trigger procd_add_interface_trigger procd_add_reload_trigger procd_add_reload_interface_trigger procd_open_trigger procd_close_trigger procd_open_instance procd_close_instance procd_open_validate procd_close_validate procd_add_jail procd_add_jail_mount procd_add_jail_mount_rw procd_set_param procd_append_param procd_add_validation procd_set_config_changed procd_kill procd_send_signal
+ procd_lock
+ readlink /etc/init.d/openvpn
+ local 'basescript='
+ basename /etc/init.d/openvpn
+ local 'service_name=openvpn'
+ flock -n 1000
+ '[' 1 '!=' 0 ]
+ exec
+ flock 1000
+ '[' 0 '!=' 0 ]
+ '[' -n procd_open_service ]
+ eval 'procd_open_service() { _procd_call _procd_open_service "$@"; }'
+ shift
+ '[' -n procd_close_service ]
+ eval 'procd_close_service() { _procd_call _procd_close_service "$@"; }'
+ shift
+ '[' -n procd_add_instance ]
+ eval 'procd_add_instance() { _procd_call _procd_add_instance "$@"; }'
+ shift
+ '[' -n procd_add_raw_trigger ]
+ eval 'procd_add_raw_trigger() { _procd_call _procd_add_raw_trigger "$@"; }'
+ shift
+ '[' -n procd_add_config_trigger ]
+ eval 'procd_add_config_trigger() { _procd_call _procd_add_config_trigger "$@"; }'
+ shift
+ '[' -n procd_add_interface_trigger ]
+ eval 'procd_add_interface_trigger() { _procd_call _procd_add_interface_trigger "$@"; }'
+ shift
+ '[' -n procd_add_reload_trigger ]
+ eval 'procd_add_reload_trigger() { _procd_call _procd_add_reload_trigger "$@"; }'
+ shift
+ '[' -n procd_add_reload_interface_trigger ]
+ eval 'procd_add_reload_interface_trigger() { _procd_call _procd_add_reload_interface_trigger "$@"; }'
+ shift
+ '[' -n procd_open_trigger ]
+ eval 'procd_open_trigger() { _procd_call _procd_open_trigger "$@"; }'
+ shift
+ '[' -n procd_close_trigger ]
+ eval 'procd_close_trigger() { _procd_call _procd_close_trigger "$@"; }'
+ shift
+ '[' -n procd_open_instance ]
+ eval 'procd_open_instance() { _procd_call _procd_open_instance "$@"; }'
+ shift
+ '[' -n procd_close_instance ]
+ eval 'procd_close_instance() { _procd_call _procd_close_instance "$@"; }'
+ shift
+ '[' -n procd_open_validate ]
+ eval 'procd_open_validate() { _procd_call _procd_open_validate "$@"; }'
+ shift
+ '[' -n procd_close_validate ]
+ eval 'procd_close_validate() { _procd_call _procd_close_validate "$@"; }'
+ shift
+ '[' -n procd_add_jail ]
+ eval 'procd_add_jail() { _procd_call _procd_add_jail "$@"; }'
+ shift
+ '[' -n procd_add_jail_mount ]
+ eval 'procd_add_jail_mount() { _procd_call _procd_add_jail_mount "$@"; }'
+ shift
+ '[' -n procd_add_jail_mount_rw ]
+ eval 'procd_add_jail_mount_rw() { _procd_call _procd_add_jail_mount_rw "$@"; }'
+ shift
+ '[' -n procd_set_param ]
+ eval 'procd_set_param() { _procd_call _procd_set_param "$@"; }'
+ shift
+ '[' -n procd_append_param ]
+ eval 'procd_append_param() { _procd_call _procd_append_param "$@"; }'
+ shift
+ '[' -n procd_add_validation ]
+ eval 'procd_add_validation() { _procd_call _procd_add_validation "$@"; }'
+ shift
+ '[' -n procd_set_config_changed ]
+ eval 'procd_set_config_changed() { _procd_call _procd_set_config_changed "$@"; }'
+ shift
+ '[' -n procd_kill ]
+ eval 'procd_kill() { _procd_call _procd_kill "$@"; }'
+ shift
+ '[' -n procd_send_signal ]
+ eval 'procd_send_signal() { _procd_call _procd_send_signal "$@"; }'
+ shift
+ '[' -n  ]
+ readlink /etc/init.d/openvpn
+ basescript=
+ ALL_COMMANDS='start stop reload restart boot shutdown enable disable enabled depends  running trace'
+ list_contains ALL_COMMANDS running
+ local 'var=ALL_COMMANDS'
+ local 'str=running'
+ local val
+ eval 'val=" ${ALL_COMMANDS} "'
+ val=' start stop reload restart boot shutdown enable disable enabled depends  running trace '
+ '[' ' start stop reload restart boot shutdown enable disable enabled depends ' '!=' ' start stop reload restart boot shutdown enable disable enabled depends  running trace ' ]
+ running
+ service_running
+ return 0

logread after boot:

Mon Jan 13 01:55:31 2020 kern.notice kernel: [    0.000000] Linux version 4.14.156 (builder@buildhost) (gcc version 7.5.0 (OpenWrt GCC 7.5.0 r10775-db8345d8e4)) #0 Sat Nov 30 15:52:33 2019
Mon Jan 13 01:55:31 2020 kern.info kernel: [    0.000000] bootconsole [early0] enabled
Mon Jan 13 01:55:31 2020 kern.info kernel: [    0.000000] CPU0 revision is: 00019750 (MIPS 74Kc)
Mon Jan 13 01:55:31 2020 kern.info kernel: [    0.000000] MIPS: machine is TP-Link Archer C7 v2
Mon Jan 13 01:55:31 2020 kern.info kernel: [    0.000000] SoC: Qualcomm Atheros QCA9558 ver 1 rev 0
Mon Jan 13 01:55:31 2020 kern.info kernel: [    0.000000] Determined physical RAM map:
Mon Jan 13 01:55:31 2020 kern.info kernel: [    0.000000]  memory: 08000000 @ 00000000 (usable)
Mon Jan 13 01:55:31 2020 kern.info kernel: [    0.000000] Initrd not found or empty - disabling initrd
Mon Jan 13 01:55:31 2020 kern.warn kernel: [    0.000000] Primary instruction cache 64kB, VIPT, 4-way, linesize 32 bytes.
Mon Jan 13 01:55:31 2020 kern.warn kernel: [    0.000000] Primary data cache 32kB, 4-way, VIPT, cache aliases, linesize 32 bytes
Mon Jan 13 01:55:31 2020 kern.info kernel: [    0.000000] Zone ranges:
Mon Jan 13 01:55:31 2020 kern.info kernel: [    0.000000]   Normal   [mem 0x0000000000000000-0x0000000007ffffff]
Mon Jan 13 01:55:31 2020 kern.info kernel: [    0.000000] Movable zone start for each node
Mon Jan 13 01:55:31 2020 kern.info kernel: [    0.000000] Early memory node ranges
Mon Jan 13 01:55:31 2020 kern.info kernel: [    0.000000]   node   0: [mem 0x0000000000000000-0x0000000007ffffff]
Mon Jan 13 01:55:31 2020 kern.info kernel: [    0.000000] Initmem setup node 0 [mem 0x0000000000000000-0x0000000007ffffff]
Mon Jan 13 01:55:31 2020 kern.debug kernel: [    0.000000] On node 0 totalpages: 32768
Mon Jan 13 01:55:31 2020 kern.debug kernel: [    0.000000] free_area_init_node: node 0, pgdat 804f9f90, node_mem_map 81008c20
Mon Jan 13 01:55:31 2020 kern.debug kernel: [    0.000000]   Normal zone: 256 pages used for memmap
Mon Jan 13 01:55:31 2020 kern.debug kernel: [    0.000000]   Normal zone: 0 pages reserved
Mon Jan 13 01:55:31 2020 kern.debug kernel: [    0.000000]   Normal zone: 32768 pages, LIFO batch:7
Mon Jan 13 01:55:31 2020 kern.notice kernel: [    0.000000] random: get_random_bytes called from 0x804fd740 with crng_init=0
Mon Jan 13 01:55:31 2020 kern.debug kernel: [    0.000000] pcpu-alloc: s0 r0 d32768 u32768 alloc=1*32768
Mon Jan 13 01:55:31 2020 kern.debug kernel: [    0.000000] pcpu-alloc: [0] 0
Mon Jan 13 01:55:31 2020 kern.info kernel: [    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 32512
Mon Jan 13 01:55:31 2020 kern.notice kernel: [    0.000000] Kernel command line: console=ttyS0,115200n8 rootfstype=squashfs,jffs2
Mon Jan 13 01:55:31 2020 kern.info kernel: [    0.000000] PID hash table entries: 512 (order: -1, 2048 bytes)
Mon Jan 13 01:55:31 2020 kern.info kernel: [    0.000000] Dentry cache hash table entries: 16384 (order: 4, 65536 bytes)
Mon Jan 13 01:55:31 2020 kern.info kernel: [    0.000000] Inode-cache hash table entries: 8192 (order: 3, 32768 bytes)
Mon Jan 13 01:55:31 2020 kern.info kernel: [    0.000000] Writing ErrCtl register=00000000
Mon Jan 13 01:55:31 2020 kern.info kernel: [    0.000000] Readback ErrCtl register=00000000
Mon Jan 13 01:55:31 2020 kern.info kernel: [    0.000000] Memory: 123304K/131072K available (4025K kernel code, 158K rwdata, 532K rodata, 1228K init, 211K bss, 7768K reserved, 0K cma-reserved)
Mon Jan 13 01:55:31 2020 kern.info kernel: [    0.000000] SLUB: HWalign=32, Order=0-3, MinObjects=0, CPUs=1, Nodes=1
Mon Jan 13 01:55:31 2020 kern.info kernel: [    0.000000] NR_IRQS: 51
Mon Jan 13 01:55:31 2020 kern.info kernel: [    0.000000] CPU clock: 720.000 MHz
Mon Jan 13 01:55:31 2020 kern.info kernel: [    0.000000] clocksource: MIPS: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 5309056796 ns
Mon Jan 13 01:55:31 2020 kern.info kernel: [    0.000008] sched_clock: 32 bits at 360MHz, resolution 2ns, wraps every 5965232126ns
Mon Jan 13 01:55:31 2020 kern.info kernel: [    0.008296] Calibrating delay loop... 358.80 BogoMIPS (lpj=1794048)
Mon Jan 13 01:55:31 2020 kern.info kernel: [    0.074993] pid_max: default: 32768 minimum: 301
Mon Jan 13 01:55:31 2020 kern.info kernel: [    0.080073] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes)
Mon Jan 13 01:55:31 2020 kern.info kernel: [    0.087111] Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes)
Mon Jan 13 01:55:31 2020 kern.info kernel: [    0.098194] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
Mon Jan 13 01:55:31 2020 kern.info kernel: [    0.108691] futex hash table entries: 256 (order: -1, 3072 bytes)
Mon Jan 13 01:55:31 2020 kern.info kernel: [    0.115246] pinctrl core: initialized pinctrl subsystem
Mon Jan 13 01:55:31 2020 kern.info kernel: [    0.122651] NET: Registered protocol family 16
Mon Jan 13 01:55:31 2020 kern.info kernel: [    0.129928] PCI host bridge /ahb/apb/pcie-controller@18250000 ranges:
Mon Jan 13 01:55:31 2020 kern.info kernel: [    0.136835]  MEM 0x0000000012000000..0x0000000013ffffff
Mon Jan 13 01:55:31 2020 kern.info kernel: [    0.142391]   IO 0x0000000000000001..0x0000000000000001
Mon Jan 13 01:55:31 2020 kern.info kernel: [    0.162394] PCI host bridge to bus 0000:00
Mon Jan 13 01:55:31 2020 kern.info kernel: [    0.166756] pci_bus 0000:00: root bus resource [mem 0x12000000-0x13ffffff]
Mon Jan 13 01:55:31 2020 kern.info kernel: [    0.174102] pci_bus 0000:00: root bus resource [io  0x0001]
Mon Jan 13 01:55:31 2020 kern.info kernel: [    0.180030] pci_bus 0000:00: root bus resource [??? 0x00000000 flags 0x0]
Mon Jan 13 01:55:31 2020 kern.info kernel: [    0.187256] pci_bus 0000:00: No busn resource found for root bus, will use [bus 00-ff]
Mon Jan 13 01:55:31 2020 kern.debug kernel: [    0.195704] pci 0000:00:00.0: [168c:003c] type 00 class 0x028000
Mon Jan 13 01:55:31 2020 kern.debug kernel: [    0.195758] pci 0000:00:00.0: reg 0x10: [mem 0x00000000-0x001fffff 64bit]
Mon Jan 13 01:55:31 2020 kern.debug kernel: [    0.195813] pci 0000:00:00.0: reg 0x30: [mem 0x00000000-0x0000ffff pref]
Mon Jan 13 01:55:31 2020 kern.debug kernel: [    0.195886] pci 0000:00:00.0: supports D1 D2
Mon Jan 13 01:55:31 2020 kern.debug kernel: [    0.196064] pci_bus 0000:00: busn_res: [bus 00-ff] end is updated to 00
Mon Jan 13 01:55:31 2020 kern.info kernel: [    0.196086] pci 0000:00:00.0: BAR 0: assigned [mem 0x12000000-0x121fffff 64bit]
Mon Jan 13 01:55:31 2020 kern.info kernel: [    0.203907] pci 0000:00:00.0: BAR 6: assigned [mem 0x12200000-0x1220ffff pref]
Mon Jan 13 01:55:31 2020 kern.info kernel: [    0.213956] clocksource: Switched to clocksource MIPS
Mon Jan 13 01:55:31 2020 kern.info kernel: [    0.220168] NET: Registered protocol family 2
Mon Jan 13 01:55:31 2020 kern.info kernel: [    0.225550] TCP established hash table entries: 1024 (order: 0, 4096 bytes)
Mon Jan 13 01:55:31 2020 kern.info kernel: [    0.232973] TCP bind hash table entries: 1024 (order: 0, 4096 bytes)
Mon Jan 13 01:55:31 2020 kern.info kernel: [    0.239781] TCP: Hash tables configured (established 1024 bind 1024)
Mon Jan 13 01:55:31 2020 kern.info kernel: [    0.246654] UDP hash table entries: 256 (order: 0, 4096 bytes)
Mon Jan 13 01:55:31 2020 kern.info kernel: [    0.252876] UDP-Lite hash table entries: 256 (order: 0, 4096 bytes)
Mon Jan 13 01:55:31 2020 kern.info kernel: [    0.259770] NET: Registered protocol family 1
Mon Jan 13 01:55:31 2020 kern.debug kernel: [    0.264471] PCI: CLS 0 bytes, default 32
Mon Jan 13 01:55:31 2020 kern.warn kernel: [    0.266825] Crashlog allocated RAM at address 0x3f00000
Mon Jan 13 01:55:31 2020 kern.info kernel: [    0.273393] workingset: timestamp_bits=30 max_order=15 bucket_order=0
Mon Jan 13 01:55:31 2020 kern.info kernel: [    0.284328] squashfs: version 4.0 (2009/01/31) Phillip Lougher
Mon Jan 13 01:55:31 2020 kern.info kernel: [    0.290528] jffs2: version 2.2 (NAND) (SUMMARY) (LZMA) (RTIME) (CMODE_PRIORITY) (c) 2001-2006 Red Hat, Inc.
Mon Jan 13 01:55:31 2020 kern.info kernel: [    0.308390] io scheduler noop registered
Mon Jan 13 01:55:31 2020 kern.info kernel: [    0.312562] io scheduler deadline registered (default)
Mon Jan 13 01:55:31 2020 kern.err kernel: [    0.318346] ar7200-usb-phy 18030000.usb-phy0: phy reset is missing
Mon Jan 13 01:55:31 2020 kern.err kernel: [    0.325004] ar7200-usb-phy 18030010.usb-phy1: phy reset is missing
Mon Jan 13 01:55:31 2020 kern.info kernel: [    0.333176] pinctrl-single 1804002c.pinmux: 544 pins at pa b804002c size 68
Mon Jan 13 01:55:31 2020 kern.info kernel: [    0.341105] gpio-export gpio-export: 2 gpio(s) exported
Mon Jan 13 01:55:31 2020 kern.info kernel: [    0.347433] Serial: 8250/16550 driver, 16 ports, IRQ sharing enabled
Mon Jan 13 01:55:31 2020 kern.info kernel: [    0.356235] console [ttyS0] disabled
Mon Jan 13 01:55:31 2020 kern.info kernel: [    0.360071] 18020000.uart: ttyS0 at MMIO 0x18020000 (irq = 9, base_baud = 2500000) is a 16550A
Mon Jan 13 01:55:31 2020 kern.info kernel: [    0.369283] console [ttyS0] enabled
Mon Jan 13 01:55:31 2020 kern.info kernel: [    0.376701] bootconsole [early0] disabled
Mon Jan 13 01:55:31 2020 kern.info kernel: [    0.402896] m25p80 spi0.0: w25q128 (16384 Kbytes)
Mon Jan 13 01:55:31 2020 kern.notice kernel: [    0.407744] 3 fixed-partitions partitions found on MTD device spi0.0
Mon Jan 13 01:55:31 2020 kern.notice kernel: [    0.414202] Creating 3 MTD partitions on "spi0.0":
Mon Jan 13 01:55:31 2020 kern.notice kernel: [    0.419070] 0x000000000000-0x000000020000 : "u-boot"
Mon Jan 13 01:55:31 2020 kern.notice kernel: [    0.424764] 0x000000020000-0x000000ff0000 : "firmware"
Mon Jan 13 01:55:31 2020 kern.notice kernel: [    0.430937] 2 tplink-fw partitions found on MTD device firmware
Mon Jan 13 01:55:31 2020 kern.notice kernel: [    0.436984] Creating 2 MTD partitions on "firmware":
Mon Jan 13 01:55:31 2020 kern.notice kernel: [    0.442030] 0x000000000000-0x0000001857af : "kernel"
Mon Jan 13 01:55:31 2020 kern.notice kernel: [    0.447677] 0x0000001857b0-0x000000fd0000 : "rootfs"
Mon Jan 13 01:55:31 2020 kern.notice kernel: [    0.453289] mtd: device 3 (rootfs) set to be root filesystem
Mon Jan 13 01:55:31 2020 kern.notice kernel: [    0.459107] 1 squashfs-split partitions found on MTD device rootfs
Mon Jan 13 01:55:31 2020 kern.notice kernel: [    0.465404] 0x000000440000-0x000000fd0000 : "rootfs_data"
Mon Jan 13 01:55:31 2020 kern.notice kernel: [    0.471480] 0x000000ff0000-0x000001000000 : "art"
Mon Jan 13 01:55:31 2020 kern.info kernel: [    0.477843] libphy: Fixed MDIO Bus: probed
Mon Jan 13 01:55:31 2020 kern.info kernel: [    0.825668] libphy: ag71xx_mdio: probed
Mon Jan 13 01:55:31 2020 kern.info kernel: [    0.832208] switch0: Atheros AR8327 rev. 4 switch registered on mdio-bus.0
Mon Jan 13 01:55:31 2020 kern.info kernel: [    1.483263] ag71xx 19000000.eth: connected to PHY at mdio-bus.0:00 [uid=004dd034, driver=Atheros AR8216/AR8236/AR8316]
Mon Jan 13 01:55:31 2020 kern.info kernel: [    1.494650] eth0: Atheros AG71xx at 0xb9000000, irq 4, mode: rgmii
Mon Jan 13 01:55:31 2020 kern.info kernel: [    1.835541] ag71xx 1a000000.eth: connected to PHY at fixed-0:00 [uid=00000000, driver=Generic PHY]
Mon Jan 13 01:55:31 2020 kern.info kernel: [    1.845154] eth1: Atheros AG71xx at 0xba000000, irq 5, mode: sgmii
Mon Jan 13 01:55:31 2020 kern.info kernel: [    1.853613] NET: Registered protocol family 10
Mon Jan 13 01:55:31 2020 kern.info kernel: [    1.862570] Segment Routing with IPv6
Mon Jan 13 01:55:31 2020 kern.info kernel: [    1.866419] NET: Registered protocol family 17
Mon Jan 13 01:55:31 2020 kern.info kernel: [    1.870972] bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this.
Mon Jan 13 01:55:31 2020 kern.info kernel: [    1.884129] 8021q: 802.1Q VLAN Support v1.8
Mon Jan 13 01:55:31 2020 kern.info kernel: [    1.890588] hctosys: unable to open rtc device (rtc0)
Mon Jan 13 01:55:31 2020 kern.info kernel: [    1.902687] VFS: Mounted root (squashfs filesystem) readonly on device 31:3.
Mon Jan 13 01:55:31 2020 kern.info kernel: [    1.914309] Freeing unused kernel memory: 1228K
Mon Jan 13 01:55:31 2020 kern.warn kernel: [    1.918906] This architecture does not have kernel memory protection.
Mon Jan 13 01:55:31 2020 kern.notice kernel: [    2.583971] random: fast init done
Mon Jan 13 01:55:31 2020 user.info kernel: [    2.627097] init: Console is alive
Mon Jan 13 01:55:31 2020 user.info kernel: [    2.630724] init: - watchdog -
Mon Jan 13 01:55:31 2020 user.info kernel: [    3.817803] kmodloader: loading kernel modules from /etc/modules-boot.d/*
Mon Jan 13 01:55:31 2020 kern.info kernel: [    4.082956] usbcore: registered new interface driver usbfs
Mon Jan 13 01:55:31 2020 kern.info kernel: [    4.088634] usbcore: registered new interface driver hub
Mon Jan 13 01:55:31 2020 kern.info kernel: [    4.094119] usbcore: registered new device driver usb
Mon Jan 13 01:55:31 2020 kern.info kernel: [    4.104368] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
Mon Jan 13 01:55:31 2020 kern.info kernel: [    4.112306] ehci-platform: EHCI generic platform driver
Mon Jan 13 01:55:31 2020 kern.info kernel: [    4.117878] ehci-platform 1b000000.usb: EHCI Host Controller
Mon Jan 13 01:55:31 2020 kern.info kernel: [    4.123664] ehci-platform 1b000000.usb: new USB bus registered, assigned bus number 1
Mon Jan 13 01:55:31 2020 kern.info kernel: [    4.131702] ehci-platform 1b000000.usb: irq 14, io mem 0x1b000000
Mon Jan 13 01:55:31 2020 kern.info kernel: [    4.164030] ehci-platform 1b000000.usb: USB 2.0 started, EHCI 1.00
Mon Jan 13 01:55:31 2020 kern.info kernel: [    4.170992] hub 1-0:1.0: USB hub found
Mon Jan 13 01:55:31 2020 kern.info kernel: [    4.175108] hub 1-0:1.0: 1 port detected
Mon Jan 13 01:55:31 2020 kern.info kernel: [    4.179596] ehci-platform 1b400000.usb: EHCI Host Controller
Mon Jan 13 01:55:31 2020 kern.info kernel: [    4.185411] ehci-platform 1b400000.usb: new USB bus registered, assigned bus number 2
Mon Jan 13 01:55:31 2020 kern.info kernel: [    4.193424] ehci-platform 1b400000.usb: irq 15, io mem 0x1b400000
Mon Jan 13 01:55:31 2020 kern.info kernel: [    4.223982] ehci-platform 1b400000.usb: USB 2.0 started, EHCI 1.00
Mon Jan 13 01:55:31 2020 kern.info kernel: [    4.230947] hub 2-0:1.0: USB hub found
Mon Jan 13 01:55:31 2020 kern.info kernel: [    4.235071] hub 2-0:1.0: 1 port detected
Mon Jan 13 01:55:31 2020 user.info kernel: [    4.239993] kmodloader: done loading kernel modules from /etc/modules-boot.d/*
Mon Jan 13 01:55:31 2020 user.info kernel: [    4.257722] init: - preinit -
Mon Jan 13 01:55:31 2020 kern.notice kernel: [    5.426789] random: jshn: uninitialized urandom read (4 bytes read)
Mon Jan 13 01:55:31 2020 kern.notice kernel: [    5.670777] random: jshn: uninitialized urandom read (4 bytes read)
Mon Jan 13 01:55:31 2020 kern.notice kernel: [    5.837078] random: jshn: uninitialized urandom read (4 bytes read)
Mon Jan 13 01:55:31 2020 kern.info kernel: [    7.096378] IPv6: ADDRCONF(NETDEV_UP): eth1: link is not ready
Mon Jan 13 01:55:31 2020 kern.info kernel: [    7.102362] IPv6: ADDRCONF(NETDEV_UP): eth1.1: link is not ready
Mon Jan 13 01:55:31 2020 kern.warn kernel: [    7.143714] urandom_read: 4 callbacks suppressed
Mon Jan 13 01:55:31 2020 kern.notice kernel: [    7.143721] random: procd: uninitialized urandom read (4 bytes read)
Mon Jan 13 01:55:31 2020 kern.info kernel: [    8.115092] eth1: link up (1000Mbps/Full duplex)
Mon Jan 13 01:55:31 2020 kern.info kernel: [    8.119800] IPv6: ADDRCONF(NETDEV_CHANGE): eth1: link becomes ready
Mon Jan 13 01:55:31 2020 kern.info kernel: [    8.126474] IPv6: ADDRCONF(NETDEV_CHANGE): eth1.1: link becomes ready
Mon Jan 13 01:55:31 2020 kern.notice kernel: [   10.441621] jffs2: notice: (488) jffs2_build_xattr_subsystem: complete building xattr subsystem, 19 of xdatum (3 unchecked, 16 orphan) and 66 of xref (16 dead, 0 orphan) found.
Mon Jan 13 01:55:31 2020 user.info kernel: [   10.461184] mount_root: switching to jffs2 overlay
Mon Jan 13 01:55:31 2020 kern.warn kernel: [   10.488049] overlayfs: upper fs does not support tmpfile.
Mon Jan 13 01:55:31 2020 user.warn kernel: [   10.501668] urandom-seed: Seeding with /etc/urandom.seed
Mon Jan 13 01:55:31 2020 kern.info kernel: [   10.619142] eth1: link down
Mon Jan 13 01:55:31 2020 user.info kernel: [   10.642532] procd: - early -
Mon Jan 13 01:55:31 2020 user.info kernel: [   10.741256] procd: - watchdog -
Mon Jan 13 01:55:31 2020 user.info kernel: [   11.306094] procd: - watchdog -
Mon Jan 13 01:55:31 2020 user.info kernel: [   11.309534] procd: - ubus -
Mon Jan 13 01:55:31 2020 kern.notice kernel: [   11.375126] random: ubusd: uninitialized urandom read (4 bytes read)
Mon Jan 13 01:55:31 2020 kern.notice kernel: [   11.383302] random: ubusd: uninitialized urandom read (4 bytes read)
Mon Jan 13 01:55:31 2020 user.info kernel: [   11.390938] procd: - init -
Mon Jan 13 01:55:31 2020 user.info kernel: [   12.453605] urngd: v1.0.0 started.
Mon Jan 13 01:55:31 2020 user.info kernel: [   12.515126] kmodloader: loading kernel modules from /etc/modules.d/*
Mon Jan 13 01:55:31 2020 kern.info kernel: [   12.566097] tun: Universal TUN/TAP device driver, 1.6
Mon Jan 13 01:55:31 2020 kern.info kernel: [   12.586224] ip6_tables: (C) 2000-2006 Netfilter Core Team
Mon Jan 13 01:55:31 2020 kern.info kernel: [   12.610533] Loading modules backported from Linux version v4.19.85-0-gc63ee2939dc1
Mon Jan 13 01:55:31 2020 kern.info kernel: [   12.618250] Backport generated by backports.git v4.19.85-1-0-g8a8be258
Mon Jan 13 01:55:31 2020 kern.info kernel: [   12.645972] ip_tables: (C) 2000-2006 Netfilter Core Team
Mon Jan 13 01:55:31 2020 kern.info kernel: [   12.658613] nf_conntrack version 0.5.0 (2048 buckets, 8192 max)
Mon Jan 13 01:55:31 2020 kern.notice kernel: [   12.714950] random: crng init done
Mon Jan 13 01:55:31 2020 kern.info kernel: [   12.746723] xt_time: kernel timezone is -0000
Mon Jan 13 01:55:31 2020 kern.info kernel: [   12.796562] PPP generic driver version 2.4.2
Mon Jan 13 01:55:31 2020 kern.info kernel: [   12.802892] NET: Registered protocol family 24
Mon Jan 13 01:55:31 2020 kern.info kernel: [   12.831456] ath10k 4.19 driver, optimized for CT firmware, probing pci device: 0x3c.
Mon Jan 13 01:55:31 2020 kern.warn kernel: [   12.840706] PCI: Enabling device 0000:00:00.0 (0000 -> 0002)
Mon Jan 13 01:55:31 2020 kern.info kernel: [   12.846639] ath10k_pci 0000:00:00.0: pci irq legacy oper_irq_mode 1 irq_mode 0 reset_mode 0
Mon Jan 13 01:55:31 2020 kern.warn kernel: [   13.128534] ath10k_pci 0000:00:00.0: Direct firmware load for ath10k/fwcfg-pci-0000:00:00.0.txt failed with error -2
Mon Jan 13 01:55:31 2020 kern.warn kernel: [   13.139245] ath10k_pci 0000:00:00.0: Falling back to user helper
Mon Jan 13 01:55:31 2020 kern.err kernel: [   13.286864] firmware ath10k!fwcfg-pci-0000:00:00.0.txt: firmware_loading_store: map pages failed
Mon Jan 13 01:55:31 2020 kern.warn kernel: [   13.296054] ath10k_pci 0000:00:00.0: Direct firmware load for ath10k/pre-cal-pci-0000:00:00.0.bin failed with error -2
Mon Jan 13 01:55:31 2020 kern.warn kernel: [   13.306933] ath10k_pci 0000:00:00.0: Falling back to user helper
Mon Jan 13 01:55:31 2020 kern.err kernel: [   13.523485] firmware ath10k!pre-cal-pci-0000:00:00.0.bin: firmware_loading_store: map pages failed
Mon Jan 13 01:55:31 2020 kern.warn kernel: [   13.535083] ath10k_pci 0000:00:00.0: Direct firmware load for ath10k/QCA988X/hw2.0/ct-firmware-5.bin failed with error -2
Mon Jan 13 01:55:31 2020 kern.warn kernel: [   13.546240] ath10k_pci 0000:00:00.0: Falling back to user helper
Mon Jan 13 01:55:31 2020 kern.err kernel: [   13.728197] firmware ath10k!QCA988X!hw2.0!ct-firmware-5.bin: firmware_loading_store: map pages failed
Mon Jan 13 01:55:31 2020 kern.warn kernel: [   13.737821] ath10k_pci 0000:00:00.0: Direct firmware load for ath10k/QCA988X/hw2.0/ct-firmware-2.bin failed with error -2
Mon Jan 13 01:55:31 2020 kern.warn kernel: [   13.748964] ath10k_pci 0000:00:00.0: Falling back to user helper
Mon Jan 13 01:55:31 2020 kern.err kernel: [   13.935829] firmware ath10k!QCA988X!hw2.0!ct-firmware-2.bin: firmware_loading_store: map pages failed
Mon Jan 13 01:55:31 2020 kern.warn kernel: [   13.945444] ath10k_pci 0000:00:00.0: Direct firmware load for ath10k/QCA988X/hw2.0/firmware-6.bin failed with error -2
Mon Jan 13 01:55:31 2020 kern.warn kernel: [   13.956328] ath10k_pci 0000:00:00.0: Falling back to user helper
Mon Jan 13 01:55:31 2020 kern.err kernel: [   14.136797] firmware ath10k!QCA988X!hw2.0!firmware-6.bin: firmware_loading_store: map pages failed
Mon Jan 13 01:55:31 2020 kern.warn kernel: [   14.146156] ath10k_pci 0000:00:00.0: Direct firmware load for ath10k/QCA988X/hw2.0/firmware-5.bin failed with error -2
Mon Jan 13 01:55:31 2020 kern.warn kernel: [   14.157038] ath10k_pci 0000:00:00.0: Falling back to user helper
Mon Jan 13 01:55:31 2020 kern.err kernel: [   14.341914] firmware ath10k!QCA988X!hw2.0!firmware-5.bin: firmware_loading_store: map pages failed
Mon Jan 13 01:55:31 2020 kern.warn kernel: [   14.351269] ath10k_pci 0000:00:00.0: Direct firmware load for ath10k/QCA988X/hw2.0/firmware-4.bin failed with error -2
Mon Jan 13 01:55:31 2020 kern.warn kernel: [   14.362149] ath10k_pci 0000:00:00.0: Falling back to user helper
Mon Jan 13 01:55:31 2020 kern.err kernel: [   14.547555] firmware ath10k!QCA988X!hw2.0!firmware-4.bin: firmware_loading_store: map pages failed
Mon Jan 13 01:55:31 2020 kern.warn kernel: [   14.556922] ath10k_pci 0000:00:00.0: Direct firmware load for ath10k/QCA988X/hw2.0/firmware-3.bin failed with error -2
Mon Jan 13 01:55:31 2020 kern.warn kernel: [   14.567805] ath10k_pci 0000:00:00.0: Falling back to user helper
Mon Jan 13 01:55:31 2020 kern.err kernel: [   14.750546] firmware ath10k!QCA988X!hw2.0!firmware-3.bin: firmware_loading_store: map pages failed
Mon Jan 13 01:55:31 2020 kern.info kernel: [   14.963341] ath10k_pci 0000:00:00.0: qca988x hw2.0 target 0x4100016c chip_id 0x043202ff sub 0000:0000
Mon Jan 13 01:55:31 2020 kern.info kernel: [   14.972739] ath10k_pci 0000:00:00.0: kconfig debug 0 debugfs 1 tracing 0 dfs 1 testmode 0
Mon Jan 13 01:55:31 2020 kern.info kernel: [   14.985171] ath10k_pci 0000:00:00.0: firmware ver 10.1-ct-8x-__fW-022-64cc8007 api 2 features wmi-10.x,has-wmi-mgmt-tx,mfp,txstatus-noack,wmi-10.x-CT,ratemask-CT,txrate-CT,get-temp-CT,tx-rc-CT,cust-stats-CT,retry-gt2-CT,txrate2-CT,beacon-cb-CT,wmi-block-ack-CT crc32 156478f0
Mon Jan 13 01:55:31 2020 kern.warn kernel: [   15.039634] ath10k_pci 0000:00:00.0: Direct firmware load for ath10k/QCA988X/hw2.0/board-2.bin failed with error -2
Mon Jan 13 01:55:31 2020 kern.warn kernel: [   15.050238] ath10k_pci 0000:00:00.0: Falling back to user helper
Mon Jan 13 01:55:31 2020 kern.err kernel: [   15.147510] firmware ath10k!QCA988X!hw2.0!board-2.bin: firmware_loading_store: map pages failed
Mon Jan 13 01:55:31 2020 kern.info kernel: [   15.156806] ath10k_pci 0000:00:00.0: board_file api 1 bmi_id N/A crc32 bebc7c08
Mon Jan 13 01:55:31 2020 kern.warn kernel: [   16.152639] ath10k_pci 0000:00:00.0: 10.1 wmi init: vdevs: 16  peers: 127  tid: 256
Mon Jan 13 01:55:31 2020 kern.info kernel: [   16.170105] ath10k_pci 0000:00:00.0: wmi print 'P 128 V 8 T 410'
Mon Jan 13 01:55:31 2020 kern.info kernel: [   16.176451] ath10k_pci 0000:00:00.0: wmi print 'msdu-desc: 1424  sw-crypt: 0 ct-sta: 0'
Mon Jan 13 01:55:31 2020 kern.info kernel: [   16.184600] ath10k_pci 0000:00:00.0: wmi print 'alloc rem: 20904 iram: 26072'
Mon Jan 13 01:55:31 2020 kern.info kernel: [   16.238480] ath10k_pci 0000:00:00.0: htt-ver 2.1 wmi-op 2 htt-op 2 cal file max-sta 128 raw 0 hwcrypto 1
Mon Jan 13 01:55:31 2020 kern.info kernel: [   16.252719] ath10k_pci 0000:00:00.0: NOTE:  Firmware DBGLOG output disabled in debug_mask: 0x10000000
Mon Jan 13 01:55:31 2020 kern.debug kernel: [   16.386810] ath: EEPROM regdomain: 0x0
Mon Jan 13 01:55:31 2020 kern.debug kernel: [   16.386817] ath: EEPROM indicates default country code should be used
Mon Jan 13 01:55:31 2020 kern.debug kernel: [   16.386820] ath: doing EEPROM country->regdmn map search
Mon Jan 13 01:55:31 2020 kern.debug kernel: [   16.386833] ath: country maps to regdmn code: 0x3a
Mon Jan 13 01:55:31 2020 kern.debug kernel: [   16.386838] ath: Country alpha2 being used: US
Mon Jan 13 01:55:31 2020 kern.debug kernel: [   16.386842] ath: Regpair used: 0x3a
Mon Jan 13 01:55:31 2020 kern.debug kernel: [   16.449954] ath: EEPROM regdomain: 0x0
Mon Jan 13 01:55:31 2020 kern.debug kernel: [   16.449961] ath: EEPROM indicates default country code should be used
Mon Jan 13 01:55:31 2020 kern.debug kernel: [   16.449965] ath: doing EEPROM country->regdmn map search
Mon Jan 13 01:55:31 2020 kern.debug kernel: [   16.449977] ath: country maps to regdmn code: 0x3a
Mon Jan 13 01:55:31 2020 kern.debug kernel: [   16.449982] ath: Country alpha2 being used: US
Mon Jan 13 01:55:31 2020 kern.debug kernel: [   16.449985] ath: Regpair used: 0x3a
Mon Jan 13 01:55:31 2020 kern.debug kernel: [   16.467501] ieee80211 phy1: Selected rate control algorithm 'minstrel_ht'
Mon Jan 13 01:55:31 2020 kern.info kernel: [   16.468953] ieee80211 phy1: Atheros AR9550 Rev:0 mem=0xb8100000, irq=13
Mon Jan 13 01:55:31 2020 user.info kernel: [   16.522776] kmodloader: done loading kernel modules from /etc/modules.d/*
Mon Jan 13 01:55:33 2020 daemon.info dnsmasq[1079]: started, version 2.80 cachesize 150
Mon Jan 13 01:55:33 2020 daemon.info dnsmasq[1079]: DNS service limited to local subnets
Mon Jan 13 01:55:33 2020 daemon.info dnsmasq[1079]: 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
Mon Jan 13 01:55:33 2020 daemon.info dnsmasq[1079]: using local addresses only for domain test
Mon Jan 13 01:55:33 2020 daemon.info dnsmasq[1079]: using local addresses only for domain onion
Mon Jan 13 01:55:33 2020 daemon.info dnsmasq[1079]: using local addresses only for domain localhost
Mon Jan 13 01:55:33 2020 daemon.info dnsmasq[1079]: using local addresses only for domain local
Mon Jan 13 01:55:33 2020 daemon.info dnsmasq[1079]: using local addresses only for domain invalid
Mon Jan 13 01:55:33 2020 daemon.info dnsmasq[1079]: using local addresses only for domain bind
Mon Jan 13 01:55:33 2020 daemon.info dnsmasq[1079]: using local addresses only for domain lan
Mon Jan 13 01:55:33 2020 daemon.warn dnsmasq[1079]: no servers found in /tmp/resolv.conf.auto, will retry
Mon Jan 13 01:55:33 2020 daemon.info dnsmasq[1079]: read /etc/hosts - 4 addresses
Mon Jan 13 01:55:33 2020 daemon.info dnsmasq[1079]: read /tmp/hosts/dhcp.cfg01411c - 0 addresses
Mon Jan 13 01:55:34 2020 authpriv.info dropbear[1112]: Not backgrounding
Mon Jan 13 01:55:36 2020 user.notice : Added device handler type: 8021ad
Mon Jan 13 01:55:36 2020 user.notice : Added device handler type: 8021q
Mon Jan 13 01:55:36 2020 user.notice : Added device handler type: macvlan
Mon Jan 13 01:55:36 2020 user.notice : Added device handler type: veth
Mon Jan 13 01:55:36 2020 user.notice : Added device handler type: bridge
Mon Jan 13 01:55:36 2020 user.notice : Added device handler type: Network device
Mon Jan 13 01:55:36 2020 user.notice : Added device handler type: tunnel
Mon Jan 13 01:55:38 2020 kern.info kernel: [   25.004292] IPv6: ADDRCONF(NETDEV_UP): eth1: link is not ready
Mon Jan 13 01:55:38 2020 kern.info kernel: [   25.011363] eth1: link up (1000Mbps/Full duplex)
Mon Jan 13 01:55:38 2020 kern.info kernel: [   25.025385] IPv6: ADDRCONF(NETDEV_CHANGE): eth1: link becomes ready
Mon Jan 13 01:55:38 2020 kern.info kernel: [   25.046580] br-lan: port 1(eth1.1) entered blocking state
Mon Jan 13 01:55:38 2020 kern.info kernel: [   25.052067] br-lan: port 1(eth1.1) entered disabled state
Mon Jan 13 01:55:38 2020 kern.info kernel: [   25.057923] device eth1.1 entered promiscuous mode
Mon Jan 13 01:55:38 2020 kern.info kernel: [   25.062782] device eth1 entered promiscuous mode
Mon Jan 13 01:55:38 2020 kern.info kernel: [   25.134942] br-lan: port 1(eth1.1) entered blocking state
Mon Jan 13 01:55:38 2020 kern.info kernel: [   25.140430] br-lan: port 1(eth1.1) entered forwarding state
Mon Jan 13 01:55:38 2020 kern.info kernel: [   25.146242] IPv6: ADDRCONF(NETDEV_UP): br-lan: link is not ready
Mon Jan 13 01:55:38 2020 daemon.notice netifd: Interface 'lan' is enabled
Mon Jan 13 01:55:38 2020 daemon.notice netifd: Interface 'lan' is setting up now
Mon Jan 13 01:55:38 2020 daemon.notice netifd: Interface 'lan' is now up
Mon Jan 13 01:55:38 2020 daemon.notice netifd: Interface 'loopback' is enabled
Mon Jan 13 01:55:38 2020 daemon.notice netifd: Interface 'loopback' is setting up now
Mon Jan 13 01:55:38 2020 daemon.notice netifd: Interface 'loopback' is now up
Mon Jan 13 01:55:38 2020 kern.info kernel: [   25.217851] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
Mon Jan 13 01:55:38 2020 daemon.notice netifd: Interface 'wan' is enabled
Mon Jan 13 01:55:38 2020 kern.info kernel: [   25.246298] IPv6: ADDRCONF(NETDEV_UP): eth0.2: link is not ready
Mon Jan 13 01:55:38 2020 daemon.notice netifd: Interface 'wan6' is enabled
Mon Jan 13 01:55:38 2020 daemon.notice netifd: bridge 'br-lan' link is up
Mon Jan 13 01:55:38 2020 daemon.notice netifd: Interface 'lan' has link connectivity
Mon Jan 13 01:55:38 2020 daemon.notice netifd: Network device 'eth1' link is up
Mon Jan 13 01:55:38 2020 daemon.notice netifd: VLAN 'eth1.1' link is up
Mon Jan 13 01:55:38 2020 daemon.notice netifd: Network device 'lo' link is up
Mon Jan 13 01:55:38 2020 daemon.notice netifd: Interface 'loopback' has link connectivity
Mon Jan 13 01:55:39 2020 user.notice firewall: Reloading firewall due to ifup of lan (br-lan)
Mon Jan 13 01:55:39 2020 kern.info kernel: [   26.034361] IPv6: ADDRCONF(NETDEV_CHANGE): br-lan: link becomes ready
Mon Jan 13 01:55:39 2020 kern.info kernel: [   26.284065] eth0: link up (1000Mbps/Full duplex)
Mon Jan 13 01:55:39 2020 daemon.notice netifd: Network device 'eth0' link is up
Mon Jan 13 01:55:39 2020 kern.info kernel: [   26.305410] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
Mon Jan 13 01:55:39 2020 daemon.notice netifd: VLAN 'eth0.2' link is up
Mon Jan 13 01:55:39 2020 daemon.notice netifd: Interface 'wan' has link connectivity
Mon Jan 13 01:55:39 2020 daemon.notice netifd: Interface 'wan' is setting up now
Mon Jan 13 01:55:39 2020 daemon.notice netifd: Interface 'wan6' has link connectivity
Mon Jan 13 01:55:39 2020 daemon.notice netifd: Interface 'wan6' is setting up now
Mon Jan 13 01:55:39 2020 kern.info kernel: [   26.354051] IPv6: ADDRCONF(NETDEV_CHANGE): eth0.2: link becomes ready
Mon Jan 13 01:55:40 2020 daemon.notice netifd: wan (1414): udhcpc: started, v1.30.1
Mon Jan 13 01:55:40 2020 daemon.err odhcp6c[1415]: Failed to send RS (Address not available)
Mon Jan 13 01:55:40 2020 daemon.err odhcp6c[1415]: Failed to send DHCPV6 message to ff02::1:2 (Address not available)
Mon Jan 13 01:55:41 2020 daemon.err odhcpd[1362]: Failed to send to ff02::1%lan@br-lan (Address not available)
Mon Jan 13 01:55:41 2020 daemon.notice netifd: wan (1414): udhcpc: sending discover
Mon Jan 13 01:55:41 2020 kern.info kernel: [   28.414455] IPv6: ADDRCONF(NETDEV_UP): wlan1: link is not ready
Mon Jan 13 01:55:41 2020 daemon.err odhcp6c[1415]: Failed to send DHCPV6 message to ff02::1:2 (Address not available)
Mon Jan 13 01:55:42 2020 daemon.notice wpa_supplicant[1546]: Successfully initialized wpa_supplicant
Mon Jan 13 01:55:42 2020 daemon.err hostapd: Configuration file: /var/run/hostapd-phy0.conf
Mon Jan 13 01:55:42 2020 daemon.notice procd: /etc/rc.d/S50netserver: Starting netserver with host 'IN(6)ADDR_ANY' port '12865' and family AF_UNSPEC
Mon Jan 13 01:55:42 2020 user.info travelmate-1.5.3[1279]: travelmate instance started ::: action: start, pid: 1279
Mon Jan 13 01:55:44 2020 user.notice ucitrack: Setting up /etc/config/network reload dependency on /etc/config/dhcp
Mon Jan 13 01:55:45 2020 user.notice ucitrack: Setting up /etc/config/network reload dependency on /etc/config/radvd
Mon Jan 13 01:55:45 2020 user.notice ucitrack: Setting up /etc/config/wireless reload dependency on /etc/config/network
Mon Jan 13 01:55:45 2020 kern.warn kernel: [   31.971536] ath10k_pci 0000:00:00.0: 10.1 wmi init: vdevs: 16  peers: 127  tid: 256
Mon Jan 13 01:55:45 2020 kern.info kernel: [   31.989010] ath10k_pci 0000:00:00.0: wmi print 'P 128 V 8 T 410'
Mon Jan 13 01:55:45 2020 kern.info kernel: [   31.995378] ath10k_pci 0000:00:00.0: wmi print 'msdu-desc: 1424  sw-crypt: 0 ct-sta: 0'
Mon Jan 13 01:55:45 2020 kern.info kernel: [   32.003507] ath10k_pci 0000:00:00.0: wmi print 'alloc rem: 20904 iram: 26072'
Mon Jan 13 01:55:45 2020 user.notice ucitrack: Setting up /etc/config/firewall reload dependency on /etc/config/luci-splash
Mon Jan 13 01:55:45 2020 user.notice ucitrack: Setting up /etc/config/firewall reload dependency on /etc/config/qos
Mon Jan 13 01:55:45 2020 kern.warn kernel: [   32.083225] ath10k_pci 0000:00:00.0: pdev param 0 not supported by firmware
Mon Jan 13 01:55:45 2020 kern.info kernel: [   32.112120] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
Mon Jan 13 01:55:45 2020 daemon.notice netifd: wan (1414): udhcpc: sending discover
Mon Jan 13 01:55:45 2020 user.notice ucitrack: Setting up /etc/config/firewall reload dependency on /etc/config/miniupnpd
Mon Jan 13 01:55:45 2020 kern.info kernel: [   32.192682] br-lan: port 2(wlan0) entered blocking state
Mon Jan 13 01:55:45 2020 kern.info kernel: [   32.198124] br-lan: port 2(wlan0) entered disabled state
Mon Jan 13 01:55:45 2020 kern.info kernel: [   32.203777] device wlan0 entered promiscuous mode
Mon Jan 13 01:55:45 2020 daemon.notice hostapd: wlan0: interface state UNINITIALIZED->HT_SCAN
Mon Jan 13 01:55:45 2020 daemon.notice hostapd: Switch own primary and secondary channel to get secondary channel with no Beacons from other BSSes
Mon Jan 13 01:55:45 2020 daemon.err hostapd: Using interface wlan0 with hwaddr d4:6e:0e:64:62:b5 and ssid "ilinx-roam"
Mon Jan 13 01:55:45 2020 daemon.notice netifd: Interface 'trm_wwan' is enabled
Mon Jan 13 01:55:45 2020 user.notice ucitrack: Setting up /etc/config/dhcp reload dependency on /etc/config/odhcpd
Mon Jan 13 01:55:46 2020 user.notice ucitrack: Setting up non-init /etc/config/fstab reload handler: /sbin/block mount
Mon Jan 13 01:55:46 2020 user.notice ucitrack: Setting up /etc/config/system reload trigger for non-procd /etc/init.d/led
Mon Jan 13 01:55:46 2020 kern.info kernel: [   33.438399] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
Mon Jan 13 01:55:46 2020 kern.info kernel: [   33.445041] br-lan: port 2(wlan0) entered blocking state
Mon Jan 13 01:55:46 2020 kern.info kernel: [   33.450445] br-lan: port 2(wlan0) entered forwarding state
Mon Jan 13 01:55:46 2020 daemon.notice hostapd: wlan0: interface state HT_SCAN->ENABLED
Mon Jan 13 01:55:46 2020 daemon.notice hostapd: wlan0: AP-ENABLED
Mon Jan 13 01:55:46 2020 user.notice ucitrack: Setting up /etc/config/system reload dependency on /etc/config/luci_statistics
Mon Jan 13 01:55:46 2020 user.notice ucitrack: Setting up /etc/config/system reload dependency on /etc/config/dhcp
Mon Jan 13 01:55:46 2020 daemon.notice netifd: Network device 'wlan0' link is up
Mon Jan 13 01:55:48 2020 daemon.notice netifd: wan (1414): udhcpc: sending discover
Mon Jan 13 01:55:49 2020 daemon.info procd: - init complete -
Mon Jan 13 01:55:49 2020 daemon.info dnsmasq[1079]: exiting on receipt of SIGTERM
Mon Jan 13 01:55:49 2020 daemon.info dnsmasq[1994]: started, version 2.80 cachesize 150
Mon Jan 13 01:55:49 2020 daemon.info dnsmasq[1994]: DNS service limited to local subnets
Mon Jan 13 01:55:49 2020 daemon.info dnsmasq[1994]: 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
Mon Jan 13 01:55:49 2020 daemon.info dnsmasq-dhcp[1994]: DHCP, IP range 10.75.24.100 -- 10.75.24.249, lease time 12h
Mon Jan 13 01:55:49 2020 daemon.info dnsmasq[1994]: using local addresses only for domain test
Mon Jan 13 01:55:49 2020 daemon.info dnsmasq[1994]: using local addresses only for domain onion
Mon Jan 13 01:55:49 2020 daemon.info dnsmasq[1994]: using local addresses only for domain localhost
Mon Jan 13 01:55:49 2020 daemon.info dnsmasq[1994]: using local addresses only for domain local
Mon Jan 13 01:55:49 2020 daemon.info dnsmasq[1994]: using local addresses only for domain invalid
Mon Jan 13 01:55:49 2020 daemon.info dnsmasq[1994]: using local addresses only for domain bind
Mon Jan 13 01:55:49 2020 daemon.info dnsmasq[1994]: using local addresses only for domain lan
Mon Jan 13 01:55:49 2020 daemon.warn dnsmasq[1994]: no servers found in /tmp/resolv.conf.auto, will retry
Mon Jan 13 01:55:49 2020 daemon.info dnsmasq[1994]: read /etc/hosts - 4 addresses
Mon Jan 13 01:55:49 2020 daemon.info dnsmasq[1994]: read /tmp/hosts/dhcp.cfg01411c - 2 addresses
Mon Jan 13 01:55:49 2020 daemon.info dnsmasq-dhcp[1994]: read /etc/ethers - 0 addresses
Mon Jan 13 01:55:49 2020 daemon.info dnsmasq[1994]: read /etc/hosts - 4 addresses
Mon Jan 13 01:55:49 2020 daemon.info dnsmasq[1994]: read /tmp/hosts/dhcp.cfg01411c - 2 addresses
Mon Jan 13 01:55:49 2020 daemon.info dnsmasq-dhcp[1994]: read /etc/ethers - 0 addresses
Mon Jan 13 01:55:50 2020 daemon.notice netifd: Interface 'trm_wwan' is disabled
Mon Jan 13 01:55:50 2020 daemon.notice wpa_supplicant[1695]: CTRL_IFACE: Detach monitor that cannot receive messages: /var/run/iwinfo-wlan1-2054\x00
Mon Jan 13 01:55:50 2020 daemon.notice wpa_supplicant[1695]: nl80211: deinit ifname=wlan1 disabled_11b_rates=0
Mon Jan 13 01:55:51 2020 daemon.notice wpa_supplicant[1695]: wlan1: CTRL-EVENT-TERMINATING
Mon Jan 13 01:55:51 2020 kern.info kernel: [   38.297465] IPv6: ADDRCONF(NETDEV_UP): wlan1: link is not ready
Mon Jan 13 01:55:51 2020 daemon.notice wpa_supplicant[2146]: Successfully initialized wpa_supplicant
Mon Jan 13 01:55:51 2020 daemon.notice netifd: Interface 'trm_wwan' is enabled
Mon Jan 13 01:55:52 2020 daemon.notice wpa_supplicant[2147]: wlan1: SME: Trying to authenticate with 6e:b0:ce:f5:1e:4a (SSID='ilinx-guest' freq=2422 MHz)
Mon Jan 13 01:55:53 2020 kern.info kernel: [   39.801418] wlan1: authenticate with 6e:b0:ce:f5:1e:4a
Mon Jan 13 01:55:53 2020 kern.info kernel: [   39.832489] wlan1: send auth to 6e:b0:ce:f5:1e:4a (try 1/3)
Mon Jan 13 01:55:53 2020 kern.info kernel: [   39.840875] wlan1: authenticated
Mon Jan 13 01:55:53 2020 daemon.notice wpa_supplicant[2147]: wlan1: Trying to associate with 6e:b0:ce:f5:1e:4a (SSID='ilinx-guest' freq=2422 MHz)
Mon Jan 13 01:55:53 2020 kern.info kernel: [   39.854029] wlan1: associate with 6e:b0:ce:f5:1e:4a (try 1/3)
Mon Jan 13 01:55:53 2020 kern.info kernel: [   39.865172] wlan1: RX AssocResp from 6e:b0:ce:f5:1e:4a (capab=0x431 status=0 aid=1)
Mon Jan 13 01:55:53 2020 kern.info kernel: [   39.873290] wlan1: associated
Mon Jan 13 01:55:53 2020 daemon.notice wpa_supplicant[2147]: wlan1: Associated with 6e:b0:ce:f5:1e:4a
Mon Jan 13 01:55:53 2020 daemon.notice wpa_supplicant[2147]: wlan1: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0
Mon Jan 13 01:55:53 2020 daemon.notice netifd: Network device 'wlan1' link is up
Mon Jan 13 01:55:53 2020 daemon.notice netifd: Interface 'trm_wwan' has link connectivity
Mon Jan 13 01:55:53 2020 daemon.notice netifd: Interface 'trm_wwan' is setting up now
Mon Jan 13 01:55:53 2020 daemon.notice netifd: trm_wwan (2166): udhcpc: started, v1.30.1
Mon Jan 13 01:55:53 2020 daemon.notice netifd: trm_wwan (2166): udhcpc: sending discover
Mon Jan 13 01:55:54 2020 daemon.notice wpa_supplicant[2147]: wlan1: WPA: Key negotiation completed with 6e:b0:ce:f5:1e:4a [PTK=CCMP GTK=CCMP]
Mon Jan 13 01:55:54 2020 daemon.notice wpa_supplicant[2147]: wlan1: CTRL-EVENT-CONNECTED - Connection to 6e:b0:ce:f5:1e:4a completed [id=0 id_str=]
Mon Jan 13 01:55:54 2020 kern.info kernel: [   40.875469] IPv6: ADDRCONF(NETDEV_CHANGE): wlan1: link becomes ready
Mon Jan 13 01:55:56 2020 daemon.notice netifd: trm_wwan (2166): udhcpc: sending discover
Mon Jan 13 01:55:57 2020 daemon.notice netifd: trm_wwan (2166): udhcpc: sending select for 192.168.101.236
Mon Jan 13 01:55:57 2020 daemon.notice netifd: trm_wwan (2166): udhcpc: lease of 192.168.101.236 obtained, lease time 21600
Mon Jan 13 01:55:57 2020 daemon.notice netifd: Interface 'trm_wwan' is now up
Mon Jan 13 01:55:57 2020 daemon.info dnsmasq[1994]: reading /tmp/resolv.conf.auto
Mon Jan 13 01:55:57 2020 daemon.info dnsmasq[1994]: using local addresses only for domain test
Mon Jan 13 01:55:57 2020 daemon.info dnsmasq[1994]: using local addresses only for domain onion
Mon Jan 13 01:55:57 2020 daemon.info dnsmasq[1994]: using local addresses only for domain localhost
Mon Jan 13 01:55:57 2020 daemon.info dnsmasq[1994]: using local addresses only for domain local
Mon Jan 13 01:55:57 2020 daemon.info dnsmasq[1994]: using local addresses only for domain invalid
Mon Jan 13 01:55:57 2020 daemon.info dnsmasq[1994]: using local addresses only for domain bind
Mon Jan 13 01:55:57 2020 daemon.info dnsmasq[1994]: using local addresses only for domain lan
Mon Jan 13 01:55:57 2020 daemon.info dnsmasq[1994]: using nameserver 10.75.22.247#53
Mon Jan 13 01:55:57 2020 user.notice firewall: Reloading firewall due to ifup of trm_wwan (wlan1)
Mon Jan 13 01:55:58 2020 user.info travelmate-1.5.3[1279]: connected to uplink 'radio1/ilinx-guest/-' (1/5, TP-Link Archer C7 v2, OpenWrt 19.07.0-rc2 r10775-db8345d8e4)
Mon Jan 13 13:00:12 2020 daemon.info hostapd: wlan0: STA 00:24:d7:7b:1f:24 IEEE 802.11: authenticated
Mon Jan 13 13:00:12 2020 daemon.info hostapd: wlan0: STA 00:24:d7:7b:1f:24 IEEE 802.11: associated (aid 1)
Mon Jan 13 13:00:12 2020 daemon.notice hostapd: wlan0: AP-STA-CONNECTED 00:24:d7:7b:1f:24
Mon Jan 13 13:00:12 2020 daemon.info hostapd: wlan0: STA 00:24:d7:7b:1f:24 WPA: pairwise key handshake completed (RSN)
Mon Jan 13 13:00:12 2020 daemon.info dnsmasq-dhcp[1994]: DHCPREQUEST(br-lan) 10.75.24.101 00:24:d7:7b:1f:24
Mon Jan 13 13:00:12 2020 daemon.info dnsmasq-dhcp[1994]: DHCPACK(br-lan) 10.75.24.101 00:24:d7:7b:1f:24 brian-laptop
Mon Jan 13 13:00:14 2020 daemon.info dnsmasq[1994]: read /etc/hosts - 4 addresses
Mon Jan 13 13:00:14 2020 daemon.info dnsmasq[1994]: read /tmp/hosts/odhcpd - 1 addresses
Mon Jan 13 13:00:14 2020 daemon.info dnsmasq[1994]: read /tmp/hosts/dhcp.cfg01411c - 2 addresses
Mon Jan 13 13:00:14 2020 daemon.info dnsmasq-dhcp[1994]: read /etc/ethers - 0 addresses
Mon Jan 13 13:01:21 2020 authpriv.info dropbear[14485]: Child connection from 10.75.24.101:39644
Mon Jan 13 13:01:25 2020 authpriv.notice dropbear[14485]: Password auth succeeded for 'root' from 10.75.24.101:39644
Mon Jan 13 13:02:05 2020 daemon.err uhttpd[1649]: luci: accepted login on /admin/vpn/openvpn for root from 10.75.24.101
Mon Jan 13 13:05:34 2020 authpriv.info dropbear[15262]: Child connection from 10.75.22.1:47076
Mon Jan 13 13:05:35 2020 authpriv.notice dropbear[15262]: Pubkey auth succeeded for 'root' with key sha1!! 28:1e:e5:1f:a9:a3:a3:fe:34:bc:a3:c8:78:88:d9:ee:3e:71:9e:31 from 10.75.22.1:47076
Mon Jan 13 13:15:53 2020 authpriv.info dropbear[15262]: Exit (root): Disconnect received
Mon Jan 13 13:16:01 2020 authpriv.info dropbear[16890]: Child connection from 10.75.22.1:47822
Mon Jan 13 13:16:02 2020 authpriv.notice dropbear[16890]: Pubkey auth succeeded for 'root' with key sha1!! 28:1e:e5:1f:a9:a3:a3:fe:34:bc:a3:c8:78:88:d9:ee:3e:71:9e:31 from 10.75.22.1:47822

Nothing new in log after:

# /etc/init.d/openvpn start

New logread contents after

# /etc/init.d/openvpn start ilinx
Mon Jan 13 13:19:46 2020 daemon.notice openvpn(ilinx)[17587]: OpenVPN 2.4.7 mips-openwrt-linux-gnu [SSL (OpenSSL)] [LZO] [LZ4] [EPOLL] [MH/PKTINFO] [AEAD]
Mon Jan 13 13:19:46 2020 daemon.notice openvpn(ilinx)[17587]: library versions: OpenSSL 1.1.1d  10 Sep 2019, LZO 2.10
Mon Jan 13 13:19:46 2020 daemon.warn openvpn(ilinx)[17587]: WARNING: No server certificate verification method has been enabled.  See http://openvpn.net/howto.html#mitm for more info.
Mon Jan 13 13:19:46 2020 daemon.notice openvpn(ilinx)[17587]: TCP/UDP: Preserving recently used remote address: [AF_INET]10.75.22.252:1194
Mon Jan 13 13:19:46 2020 daemon.notice openvpn(ilinx)[17587]: Socket Buffers: R=[163840->163840] S=[163840->163840]
Mon Jan 13 13:19:46 2020 daemon.notice openvpn(ilinx)[17587]: UDP link local: (not bound)
Mon Jan 13 13:19:46 2020 daemon.notice openvpn(ilinx)[17587]: UDP link remote: [AF_INET]10.75.22.252:1194

Loading...

Available keyboard shortcuts

Tasklist

Task Details

Task Editing