Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

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

Open
openwrt-bot opened this issue Jan 13, 2020 · 0 comments
Open
Labels

Comments

@openwrt-bot
Copy link

brianjmurrell:

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
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
Projects
None yet
Development

No branches or pull requests

1 participant