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#1741 - Hotplug events with ACTION=ifdown fail to set the DEVICE parameter. #8492

Closed
openwrt-bot opened this issue Aug 6, 2018 · 1 comment
Labels

Comments

@openwrt-bot
Copy link

bytesize:

Device: TP-Link Archer C7 V2
OpenWRT Version: 18.06 r7204-8be3af93b4

Description:

The scripts in /etc/hotplug.d/iface/ are called during ifdown/ifup events, setting
the environment variables ACTION, INTERFACE and DEVICE.

The problem is the DEVICE variable is not being set for ACTION=ifdown.

In my case this causes a problem for the /etc/hotplug.d/iface/11-sqm script, because if
the DEVICE variable is blank, ALL configured SQM devices are brought down on ACTION=ifdown.

I created a DEBUG version of 11-sqm to show the problem and fix the DEVICE variable, but the
underlying source of the problem remains.

Debug version of /etc/hotplug.d/iface/11-sqm

#!/bin/sh # dell modified this file

if [ "${DEVICE}" = "" ]; then
if [ "${INTERFACE}" = "" ]; then
logger -t SQM-DEBUG /etc/hotplug.d/iface/11-sqm Called with ACTION=${ACTION} INTERFACE=${INTERFACE} DEVICE=${DEVICE} ... EXITING
exit 0
else
logger -t SQM-DEBUG /etc/hotplug.d/iface/11-sqm Called with ACTION=${ACTION} INTERFACE=${INTERFACE} DEVICE=${DEVICE}
if [ "$(uci -q get network.${INTERFACE}.type)" = "bridge" ]; then
DEVICE="br-${INTERFACE}"
else
DEVICE="$(uci -q get network.${INTERFACE}.ifname)"
fi
if [ "${DEVICE}" = "" ]; then
logger -t SQM-DEBUG /etc/hotplug.d/iface/11-sqm Unable to determine DEVICE ... exiting
exit 0
fi
logger -t SQM-DEBUG /etc/hotplug.d/iface/11-sqm MODIFIED CALLING WITH ACTION=${ACTION} INTERFACE=${INTERFACE} DEVICE=${DEVICE}
fi
else
logger -t SQM-DEBUG /etc/hotplug.d/iface/11-sqm Called with ACTION=${ACTION} INTERFACE=${INTERFACE} DEVICE=${DEVICE}
fi

restart_sqm() {
/usr/lib/sqm/run.sh stop ${DEVICE}
/usr/lib/sqm/run.sh start ${DEVICE}
}

[ "$ACTION" = ifup ] && /etc/init.d/sqm enabled && restart_sqm

[ "$ACTION" = ifdown ] && /usr/lib/sqm/run.sh stop ${DEVICE}

OpenWRT Log showing the problem when different interfaces are brought down and up.

**** **** Log showing hotplug events with ACTION=ifdown called on scripts in /etc/hotplug.d/iface/ do not specify the DEVICE parameter. **** One effect is this causes ALL SQM configured interfaces to be brought down. **** **** Look for SQM-DEBUG lines (my 11-sqm debug script), and SQM lines (system) to see what is happeining. **** My debug script inserts the proper DEVICE parameter ( see *MODIFIED* lines) so only the appropriate SQM interface is brouht down. **** Sun Aug 5 21:50:57 2018 authpriv.info dropbear[19118]: Child connection from fd07:fb0e:52ba::db8:52120 Sun Aug 5 21:50:57 2018 authpriv.notice dropbear[19118]: Pubkey auth succeeded for 'root' with key sha1!! 11:f6:dc:6c:09:61:37:1d:9a:45:97:86:e5:c7:3a:b7:fa:d5:dc:7f from fd07:fb0e:52ba::db8:52120 Sun Aug 5 21:51:26 2018 daemon.err uhttpd[1763]: luci: accepted login on / for root from 192.168.1.167 **** **** Restart WAN interface from Luci **** **** Note the hotplug event with ACTION=ifdown does not specify DEVICE (my debug script recreates the proper device). **** Sun Aug 5 21:52:00 2018 user.notice SQM-DEBUG: /etc/hotplug.d/iface/11-sqm Called with ACTION=ifdown INTERFACE=wan DEVICE= Sun Aug 5 21:52:00 2018 user.notice SQM-DEBUG: /etc/hotplug.d/iface/11-sqm *MODIFIED* CALLING WITH ACTION=ifdown INTERFACE=wan DEVICE=eth0.2 Sun Aug 5 21:52:00 2018 daemon.notice netifd: wan (16000): udhcpc: received SIGTERM Sun Aug 5 21:52:00 2018 daemon.notice netifd: Interface 'wan' is now down Sun Aug 5 21:52:00 2018 daemon.notice netifd: Interface 'wan' is setting up now Sun Aug 5 21:52:00 2018 daemon.warn dnsmasq[15577]: no servers found in /tmp/resolv.conf.auto, will retry Sun Aug 5 21:52:01 2018 user.notice SQM: Stopping SQM on eth0.2 Sun Aug 5 21:52:01 2018 daemon.notice netifd: wan (19350): udhcpc: started, v1.28.3 Sun Aug 5 21:52:01 2018 daemon.notice netifd: wan (19350): udhcpc: sending discover Sun Aug 5 21:52:01 2018 daemon.err openvpn(torguard_client)[14487]: write UDP: Network unreachable (code=128) Sun Aug 5 21:52:02 2018 daemon.notice netifd: wan (19350): udhcpc: sending select for 206.212.230.237 Sun Aug 5 21:52:02 2018 daemon.notice netifd: wan (19350): udhcpc: lease of 206.212.230.237 obtained, lease time 86400 Sun Aug 5 21:52:02 2018 daemon.notice netifd: Interface 'wan' is now up Sun Aug 5 21:52:02 2018 daemon.info dnsmasq[15577]: reading /tmp/resolv.conf.auto Sun Aug 5 21:52:02 2018 daemon.info dnsmasq[15577]: using local addresses only for domain test Sun Aug 5 21:52:02 2018 daemon.info dnsmasq[15577]: using local addresses only for domain onion Sun Aug 5 21:52:02 2018 daemon.info dnsmasq[15577]: using local addresses only for domain localhost Sun Aug 5 21:52:02 2018 daemon.info dnsmasq[15577]: using local addresses only for domain local Sun Aug 5 21:52:02 2018 daemon.info dnsmasq[15577]: using local addresses only for domain invalid Sun Aug 5 21:52:02 2018 daemon.info dnsmasq[15577]: using local addresses only for domain bind Sun Aug 5 21:52:02 2018 daemon.info dnsmasq[15577]: using local addresses only for domain lan Sun Aug 5 21:52:02 2018 daemon.info dnsmasq[15577]: using nameserver 208.67.222.222#53 Sun Aug 5 21:52:02 2018 daemon.info dnsmasq[15577]: using nameserver 208.67.220.220#53 **** **** Note that hotplug event with ACTION=ifup correctly specifies the DEVICE parameter. **** Sun Aug 5 21:52:02 2018 user.notice SQM-DEBUG: /etc/hotplug.d/iface/11-sqm Called with ACTION=ifup INTERFACE=wan DEVICE=eth0.2 Sun Aug 5 21:52:02 2018 user.notice SQM: Starting SQM script: layer_cake.qos on eth0.2, in: 9600 Kbps, out: 2000 Kbps Sun Aug 5 21:52:02 2018 daemon.err insmod: module is already loaded - sch_cake Sun Aug 5 21:52:02 2018 daemon.err insmod: module is already loaded - sch_ingress Sun Aug 5 21:52:02 2018 daemon.err insmod: module is already loaded - act_mirred Sun Aug 5 21:52:02 2018 daemon.err insmod: module is already loaded - cls_fw Sun Aug 5 21:52:02 2018 daemon.err insmod: module is already loaded - cls_flow Sun Aug 5 21:52:02 2018 daemon.err insmod: module is already loaded - cls_u32 Sun Aug 5 21:52:02 2018 daemon.err insmod: module is already loaded - sch_htb Sun Aug 5 21:52:02 2018 daemon.err insmod: module is already loaded - sch_hfsc Sun Aug 5 21:52:03 2018 user.notice SQM: layer_cake.qos was started on eth0.2 successfully Sun Aug 5 21:52:03 2018 user.notice firewall: Reloading firewall due to ifup of wan (eth0.2) Sun Aug 5 21:52:06 2018 user.notice ddns-scripts[19866]: myddns_ipv4: PID '19866' started at 2018-08-05 21:52 Sun Aug 5 21:52:08 2018 user.warn ddns-scripts[19866]: myddns_ipv4: Service section disabled! - TERMINATE Sun Aug 5 21:52:09 2018 user.warn ddns-scripts[19866]: myddns_ipv4: PID '19866' exit WITH ERROR '1' at 2018-08-05 21:52 **** **** Restarting the LAN interface from Luci **** Sun Aug 5 21:52:32 2018 daemon.notice netifd: Interface 'lan' is now down Sun Aug 5 21:52:32 2018 kern.info kernel: [71559.952265] br-lan: port 2(wlan1) entered disabled state Sun Aug 5 21:52:32 2018 kern.info kernel: [71559.957785] br-lan: port 1(eth1.1) entered disabled state Sun Aug 5 21:52:32 2018 kern.info kernel: [71559.983346] device eth1.1 left promiscuous mode Sun Aug 5 21:52:32 2018 kern.info kernel: [71559.987984] device eth1 left promiscuous mode Sun Aug 5 21:52:32 2018 kern.info kernel: [71559.992556] br-lan: port 1(eth1.1) entered disabled state Sun Aug 5 21:52:32 2018 kern.info kernel: [71560.027381] IPv6: ADDRCONF(NETDEV_UP): eth1.1: link is not ready **** **** Note the hotplug event with ACTION=ifdown does not include DEVICE parameter **** Sun Aug 5 21:52:32 2018 user.notice SQM-DEBUG: /etc/hotplug.d/iface/11-sqm Called with ACTION=ifdown INTERFACE=lan DEVICE= Sun Aug 5 21:52:32 2018 kern.info kernel: [71560.059335] eth1: link down Sun Aug 5 21:52:32 2018 user.notice SQM-DEBUG: /etc/hotplug.d/iface/11-sqm *MODIFIED* CALLING WITH ACTION=ifdown INTERFACE=lan DEVICE=br-lan Sun Aug 5 21:52:32 2018 kern.info kernel: [71560.081604] device wlan1 left promiscuous mode Sun Aug 5 21:52:32 2018 kern.info kernel: [71560.086211] br-lan: port 2(wlan1) entered disabled state Sun Aug 5 21:52:32 2018 daemon.notice netifd: Interface 'lan' is disabled Sun Aug 5 21:52:32 2018 daemon.err odhcpd[1308]: setsockopt(IPV6_ADD_MEMBERSHIP): No such device Sun Aug 5 21:52:32 2018 daemon.err odhcpd[1308]: setsockopt(SO_BINDTODEVICE): No such device Sun Aug 5 21:52:32 2018 daemon.notice netifd: VLAN 'eth1.1' link is down Sun Aug 5 21:52:32 2018 daemon.notice netifd: bridge 'br-lan' link is down Sun Aug 5 21:52:32 2018 daemon.notice netifd: Interface 'lan' has link connectivity loss Sun Aug 5 21:52:32 2018 daemon.notice netifd: Network device 'eth1' link is down Sun Aug 5 21:52:33 2018 kern.info kernel: [71560.176382] eth1: link up (1000Mbps/Full duplex) Sun Aug 5 21:52:33 2018 kern.info kernel: [71560.214139] br-lan: port 1(eth1.1) entered blocking state Sun Aug 5 21:52:33 2018 kern.info kernel: [71560.219686] br-lan: port 1(eth1.1) entered disabled state Sun Aug 5 21:52:33 2018 kern.info kernel: [71560.225526] device eth1.1 entered promiscuous mode Sun Aug 5 21:52:33 2018 kern.info kernel: [71560.230424] device eth1 entered promiscuous mode Sun Aug 5 21:52:33 2018 daemon.info dnsmasq[15577]: read /etc/hosts - 4 addresses Sun Aug 5 21:52:33 2018 daemon.info dnsmasq[15577]: read /tmp/hosts/odhcpd - 0 addresses Sun Aug 5 21:52:33 2018 daemon.info dnsmasq[15577]: read /tmp/hosts/dhcp.cfg01411c - 7 addresses Sun Aug 5 21:52:33 2018 daemon.info dnsmasq-dhcp[15577]: read /etc/ethers - 0 addresses Sun Aug 5 21:52:33 2018 kern.info kernel: [71560.310085] br-lan: port 2(wlan1) entered blocking state Sun Aug 5 21:52:33 2018 kern.info kernel: [71560.315572] br-lan: port 2(wlan1) entered disabled state Sun Aug 5 21:52:33 2018 kern.info kernel: [71560.321326] device wlan1 entered promiscuous mode Sun Aug 5 21:52:33 2018 kern.info kernel: [71560.367445] br-lan: port 2(wlan1) entered blocking state Sun Aug 5 21:52:33 2018 kern.info kernel: [71560.372845] br-lan: port 2(wlan1) entered forwarding state Sun Aug 5 21:52:33 2018 kern.info kernel: [71560.378527] br-lan: port 1(eth1.1) entered blocking state Sun Aug 5 21:52:33 2018 kern.info kernel: [71560.384004] br-lan: port 1(eth1.1) entered forwarding state Sun Aug 5 21:52:33 2018 daemon.notice netifd: Interface 'lan' is enabled Sun Aug 5 21:52:33 2018 daemon.notice netifd: Interface 'lan' is setting up now Sun Aug 5 21:52:33 2018 daemon.notice netifd: Interface 'lan' is now up Sun Aug 5 21:52:33 2018 daemon.notice netifd: bridge 'br-lan' link is up Sun Aug 5 21:52:33 2018 daemon.notice netifd: Interface 'lan' has link connectivity Sun Aug 5 21:52:33 2018 daemon.notice netifd: Network device 'eth1' link is up Sun Aug 5 21:52:33 2018 daemon.notice netifd: VLAN 'eth1.1' link is up Sun Aug 5 21:52:34 2018 daemon.notice hostapd: wlan1: interface state ENABLED->DISABLED Sun Aug 5 21:52:34 2018 daemon.notice hostapd: wlan1: AP-STA-DISCONNECTED 94:65:2d:6c:f7:34 Sun Aug 5 21:52:34 2018 daemon.notice hostapd: wlan1: AP-STA-DISCONNECTED dc:bf:e9:af:8a:9a Sun Aug 5 21:52:34 2018 daemon.notice hostapd: wlan1: AP-STA-DISCONNECTED ac:22:0b:5d:b5:cf Sun Aug 5 21:52:34 2018 daemon.notice hostapd: wlan1: AP-DISABLED Sun Aug 5 21:52:34 2018 daemon.notice hostapd: wlan1: CTRL-EVENT-TERMINATING Sun Aug 5 21:52:34 2018 daemon.notice hostapd: nl80211: deinit ifname=wlan1 disabled_11b_rates=0 Sun Aug 5 21:52:34 2018 kern.info kernel: [71561.433522] device wlan1 left promiscuous mode Sun Aug 5 21:52:34 2018 kern.info kernel: [71561.438190] br-lan: port 2(wlan1) entered disabled state **** **** Note the hotplug event with ACTION=ifup correctly specifies DEVICE **** Sun Aug 5 21:52:34 2018 user.notice SQM-DEBUG: /etc/hotplug.d/iface/11-sqm Called with ACTION=ifup INTERFACE=lan DEVICE=br-lan Sun Aug 5 21:52:34 2018 daemon.notice netifd: Network device 'wlan1' link is down Sun Aug 5 21:52:35 2018 user.notice firewall: Reloading firewall due to ifup of lan (br-lan) Sun Aug 5 21:52:35 2018 daemon.info dnsmasq[15577]: read /etc/hosts - 4 addresses Sun Aug 5 21:52:35 2018 daemon.info dnsmasq[15577]: read /tmp/hosts/odhcpd - 2 addresses Sun Aug 5 21:52:35 2018 daemon.info dnsmasq[15577]: read /tmp/hosts/dhcp.cfg01411c - 7 addresses Sun Aug 5 21:52:35 2018 daemon.info dnsmasq-dhcp[15577]: read /etc/ethers - 0 addresses Sun Aug 5 21:52:36 2018 daemon.err hostapd: Configuration file: /var/run/hostapd-phy1.conf Sun Aug 5 21:52:36 2018 kern.info kernel: [71563.789521] IPv6: ADDRCONF(NETDEV_UP): wlan1: link is not ready Sun Aug 5 21:52:36 2018 kern.info kernel: [71563.994859] br-lan: port 2(wlan1) entered blocking state Sun Aug 5 21:52:36 2018 kern.info kernel: [71564.000337] br-lan: port 2(wlan1) entered disabled state Sun Aug 5 21:52:36 2018 kern.info kernel: [71564.006051] device wlan1 entered promiscuous mode Sun Aug 5 21:52:36 2018 daemon.notice hostapd: wlan1: interface state UNINITIALIZED->COUNTRY_UPDATE Sun Aug 5 21:52:36 2018 daemon.notice hostapd: wlan1: interface state COUNTRY_UPDATE->HT_SCAN Sun Aug 5 21:52:38 2018 daemon.notice hostapd: handle_probe_req: send failed Sun Aug 5 21:52:38 2018 daemon.notice hostapd: handle_probe_req: send failed Sun Aug 5 21:52:38 2018 daemon.notice hostapd: handle_probe_req: send failed Sun Aug 5 21:52:38 2018 daemon.notice hostapd: handle_probe_req: send failed Sun Aug 5 21:52:38 2018 daemon.notice hostapd: handle_probe_req: send failed Sun Aug 5 21:52:38 2018 daemon.notice hostapd: handle_probe_req: send failed Sun Aug 5 21:52:38 2018 daemon.notice hostapd: handle_probe_req: send failed Sun Aug 5 21:52:38 2018 daemon.notice hostapd: handle_probe_req: send failed Sun Aug 5 21:52:38 2018 daemon.notice hostapd: handle_probe_req: send failed Sun Aug 5 21:52:38 2018 daemon.notice hostapd: handle_probe_req: send failed Sun Aug 5 21:52:38 2018 daemon.notice hostapd: handle_probe_req: send failed Sun Aug 5 21:52:38 2018 daemon.notice hostapd: handle_probe_req: send failed Sun Aug 5 21:52:38 2018 daemon.notice hostapd: 20/40 MHz operation not permitted on channel pri=6 sec=10 based on overlapping BSSes Sun Aug 5 21:52:38 2018 daemon.err hostapd: Using interface wlan1 with hwaddr 30:b5:c2:96:70:c9 and ssid "CatDen-router" Sun Aug 5 21:52:38 2018 daemon.info dnsmasq[15577]: read /etc/hosts - 4 addresses Sun Aug 5 21:52:38 2018 daemon.info dnsmasq[15577]: read /tmp/hosts/odhcpd - 2 addresses Sun Aug 5 21:52:38 2018 daemon.info dnsmasq[15577]: read /tmp/hosts/dhcp.cfg01411c - 7 addresses Sun Aug 5 21:52:38 2018 daemon.info dnsmasq-dhcp[15577]: read /etc/ethers - 0 addresses Sun Aug 5 21:52:38 2018 kern.info kernel: [71566.021420] IPv6: ADDRCONF(NETDEV_CHANGE): wlan1: link becomes ready Sun Aug 5 21:52:38 2018 kern.info kernel: [71566.028048] br-lan: port 2(wlan1) entered blocking state Sun Aug 5 21:52:38 2018 kern.info kernel: [71566.033444] br-lan: port 2(wlan1) entered forwarding state Sun Aug 5 21:52:38 2018 daemon.notice hostapd: wlan1: interface state HT_SCAN->ENABLED Sun Aug 5 21:52:38 2018 daemon.notice hostapd: wlan1: AP-ENABLED Sun Aug 5 21:52:38 2018 daemon.notice netifd: Network device 'wlan1' link is up Sun Aug 5 21:52:41 2018 daemon.info hostapd: wlan1: STA 94:65:2d:6c:f7:34 IEEE 802.11: authenticated Sun Aug 5 21:52:41 2018 daemon.info hostapd: wlan1: STA 94:65:2d:6c:f7:34 IEEE 802.11: associated (aid 1) Sun Aug 5 21:52:41 2018 daemon.notice hostapd: wlan1: AP-STA-CONNECTED 94:65:2d:6c:f7:34 Sun Aug 5 21:52:41 2018 daemon.info hostapd: wlan1: STA 94:65:2d:6c:f7:34 WPA: pairwise key handshake completed (RSN) Sun Aug 5 21:52:41 2018 daemon.info dnsmasq-dhcp[15577]: DHCPDISCOVER(br-lan) 94:65:2d:6c:f7:34 Sun Aug 5 21:52:41 2018 daemon.info dnsmasq-dhcp[15577]: DHCPOFFER(br-lan) 192.168.1.169 94:65:2d:6c:f7:34 Sun Aug 5 21:52:41 2018 daemon.info dnsmasq-dhcp[15577]: DHCPREQUEST(br-lan) 192.168.1.169 94:65:2d:6c:f7:34 Sun Aug 5 21:52:41 2018 daemon.info dnsmasq-dhcp[15577]: DHCPACK(br-lan) 192.168.1.169 94:65:2d:6c:f7:34 OnePlus_5 Sun Aug 5 21:52:42 2018 daemon.info hostapd: wlan1: STA dc:bf:e9:af:8a:9a IEEE 802.11: authenticated Sun Aug 5 21:52:42 2018 daemon.info hostapd: wlan1: STA dc:bf:e9:af:8a:9a IEEE 802.11: associated (aid 2) Sun Aug 5 21:52:42 2018 daemon.notice hostapd: wlan1: AP-STA-CONNECTED dc:bf:e9:af:8a:9a Sun Aug 5 21:52:42 2018 daemon.info hostapd: wlan1: STA dc:bf:e9:af:8a:9a WPA: pairwise key handshake completed (RSN) Sun Aug 5 21:52:43 2018 daemon.info dnsmasq-dhcp[15577]: DHCPDISCOVER(br-lan) dc:bf:e9:af:8a:9a Sun Aug 5 21:52:43 2018 daemon.info dnsmasq-dhcp[15577]: DHCPOFFER(br-lan) 192.168.1.125 dc:bf:e9:af:8a:9a Sun Aug 5 21:52:43 2018 daemon.info dnsmasq-dhcp[15577]: DHCPREQUEST(br-lan) 192.168.1.125 dc:bf:e9:af:8a:9a Sun Aug 5 21:52:43 2018 daemon.info dnsmasq-dhcp[15577]: DHCPACK(br-lan) 192.168.1.125 dc:bf:e9:af:8a:9a android-e91ad6b278c61985 Sun Aug 5 21:52:49 2018 daemon.info hostapd: wlan1: STA ac:22:0b:5d:b5:cf IEEE 802.11: authenticated Sun Aug 5 21:52:49 2018 daemon.info hostapd: wlan1: STA ac:22:0b:5d:b5:cf IEEE 802.11: associated (aid 3) Sun Aug 5 21:52:49 2018 daemon.notice hostapd: wlan1: AP-STA-CONNECTED ac:22:0b:5d:b5:cf Sun Aug 5 21:52:49 2018 daemon.info hostapd: wlan1: STA ac:22:0b:5d:b5:cf WPA: pairwise key handshake completed (RSN) Sun Aug 5 21:52:50 2018 daemon.info dnsmasq-dhcp[15577]: DHCPDISCOVER(br-lan) ac:22:0b:5d:b5:cf Sun Aug 5 21:52:50 2018 daemon.info dnsmasq-dhcp[15577]: DHCPOFFER(br-lan) 192.168.1.142 ac:22:0b:5d:b5:cf Sun Aug 5 21:52:50 2018 daemon.info dnsmasq-dhcp[15577]: DHCPREQUEST(br-lan) 192.168.1.142 ac:22:0b:5d:b5:cf Sun Aug 5 21:52:50 2018 daemon.info dnsmasq-dhcp[15577]: DHCPACK(br-lan) 192.168.1.142 ac:22:0b:5d:b5:cf android-41e25fe580fa511f **** **** openvpn is restarted using /etc/init.d/openvpn restart **** Sun Aug 5 21:53:03 2018 daemon.err openvpn(torguard_client)[14487]: event_wait : Interrupted system call (code=4) Sun Aug 5 21:53:03 2018 daemon.notice openvpn(torguard_client)[14487]: /sbin/ifconfig tun0 0.0.0.0 Sun Aug 5 21:53:03 2018 daemon.notice netifd: Network device 'tun0' link is down Sun Aug 5 21:53:03 2018 daemon.notice netifd: Interface 'vpn' has link connectivity loss Sun Aug 5 21:53:03 2018 daemon.notice netifd: Interface 'vpn' is now down Sun Aug 5 21:53:03 2018 daemon.notice openvpn(torguard_client)[14487]: /etc/openvpn/down.sh tun0 1500 1585 10.9.0.6 10.9.0.5 init Sun Aug 5 21:53:03 2018 daemon.notice openvpn(torguard_client)[14487]: SIGTERM[hard,] received, process exiting **** **** Note hotplug called with ACTION=ifdown and no DEVICE specified **** Sun Aug 5 21:53:03 2018 user.notice SQM-DEBUG: /etc/hotplug.d/iface/11-sqm Called with ACTION=ifdown INTERFACE=vpn DEVICE= Sun Aug 5 21:53:03 2018 user.notice SQM-DEBUG: /etc/hotplug.d/iface/11-sqm *MODIFIED* CALLING WITH ACTION=ifdown INTERFACE=vpn DEVICE=tun0 Sun Aug 5 21:53:03 2018 daemon.notice netifd: Interface 'vpn' is disabled Sun Aug 5 21:53:03 2018 user.notice SQM: Stopping SQM on tun0 Sun Aug 5 21:53:04 2018 daemon.notice openvpn(torguard_client)[21573]: OpenVPN 2.4.5 mips-openwrt-linux-gnu [SSL (OpenSSL)] [LZO] [LZ4] [EPOLL] [MH/PKTINFO] [AEAD] Sun Aug 5 21:53:04 2018 daemon.notice openvpn(torguard_client)[21573]: library versions: OpenSSL 1.0.2o 27 Mar 2018, LZO 2.10 Sun Aug 5 21:53:04 2018 daemon.warn openvpn(torguard_client)[21573]: NOTE: the current --script-security setting may allow this configuration to call user-defined scripts Sun Aug 5 21:53:04 2018 daemon.notice openvpn(torguard_client)[21573]: TCP/UDP: Preserving recently used remote address: [AF_INET]204.44.121.194:443 Sun Aug 5 21:53:04 2018 daemon.notice openvpn(torguard_client)[21573]: UDP link local: (not bound) Sun Aug 5 21:53:04 2018 daemon.notice openvpn(torguard_client)[21573]: UDP link remote: [AF_INET]204.44.121.194:443 Sun Aug 5 21:53:04 2018 daemon.warn openvpn(torguard_client)[21573]: WARNING: this configuration may cache passwords in memory -- use the auth-nocache option to prevent this Sun Aug 5 21:53:04 2018 daemon.warn openvpn(torguard_client)[21573]: WARNING: 'link-mtu' is used inconsistently, local='link-mtu 1574', remote='link-mtu 1542' Sun Aug 5 21:53:04 2018 daemon.warn openvpn(torguard_client)[21573]: WARNING: 'tun-mtu' is used inconsistently, local='tun-mtu 1532', remote='tun-mtu 1500' Sun Aug 5 21:53:04 2018 daemon.notice openvpn(torguard_client)[21573]: [TG-OVPN-CA] Peer Connection Initiated with [AF_INET]204.44.121.194:443 Sun Aug 5 21:53:05 2018 daemon.err openvpn(torguard_client)[21573]: Options error: option 'redirect-gateway' cannot be used in this context ([PUSH-OPTIONS]) Sun Aug 5 21:53:05 2018 daemon.err openvpn(torguard_client)[21573]: Options error: option 'dhcp-option' cannot be used in this context ([PUSH-OPTIONS]) Sun Aug 5 21:53:05 2018 daemon.err openvpn(torguard_client)[21573]: Options error: option 'dhcp-option' cannot be used in this context ([PUSH-OPTIONS]) Sun Aug 5 21:53:05 2018 daemon.err openvpn(torguard_client)[21573]: Options error: option 'route' cannot be used in this context ([PUSH-OPTIONS]) Sun Aug 5 21:53:05 2018 daemon.notice netifd: Interface 'vpn' is enabled Sun Aug 5 21:53:05 2018 daemon.notice netifd: Network device 'tun0' link is up Sun Aug 5 21:53:05 2018 daemon.notice netifd: Interface 'vpn' has link connectivity Sun Aug 5 21:53:05 2018 daemon.notice netifd: Interface 'vpn' is setting up now Sun Aug 5 21:53:05 2018 daemon.notice netifd: Interface 'vpn' is now up Sun Aug 5 21:53:05 2018 daemon.notice openvpn(torguard_client)[21573]: TUN/TAP device tun0 opened Sun Aug 5 21:53:05 2018 daemon.notice openvpn(torguard_client)[21573]: do_ifconfig, tt->did_ifconfig_ipv6_setup=0 Sun Aug 5 21:53:05 2018 daemon.notice openvpn(torguard_client)[21573]: /sbin/ifconfig tun0 10.9.0.6 pointopoint 10.9.0.5 mtu 1500 Sun Aug 5 21:53:05 2018 daemon.notice openvpn(torguard_client)[21573]: /etc/openvpn/up.sh tun0 1500 1585 10.9.0.6 10.9.0.5 init **** **** Note hotplug called with ACTION=ifup and correct DEVICE **** Sun Aug 5 21:53:05 2018 user.notice SQM-DEBUG: /etc/hotplug.d/iface/11-sqm Called with ACTION=ifup INTERFACE=vpn DEVICE=tun0 Sun Aug 5 21:53:06 2018 user.notice SQM: Starting SQM script: piece_of_cake.qos on tun0, in: 8000 Kbps, out: 1700 Kbps Sun Aug 5 21:53:06 2018 daemon.err insmod: module is already loaded - sch_cake Sun Aug 5 21:53:06 2018 daemon.err insmod: module is already loaded - sch_ingress Sun Aug 5 21:53:06 2018 daemon.err insmod: module is already loaded - act_mirred Sun Aug 5 21:53:06 2018 daemon.err insmod: module is already loaded - cls_fw Sun Aug 5 21:53:06 2018 daemon.err insmod: module is already loaded - cls_flow Sun Aug 5 21:53:06 2018 daemon.err insmod: module is already loaded - cls_u32 Sun Aug 5 21:53:06 2018 daemon.err insmod: module is already loaded - sch_htb Sun Aug 5 21:53:06 2018 daemon.err insmod: module is already loaded - sch_hfsc Sun Aug 5 21:53:07 2018 user.notice SQM: piece_of_cake.qos was started on tun0 successfully Sun Aug 5 21:53:07 2018 user.notice firewall: Reloading firewall due to ifup of vpn (tun0) Sun Aug 5 21:53:07 2018 daemon.notice openvpn(torguard_client)[21573]: Initialization Sequence Completed
@openwrt-bot
Copy link
Author

bytesize:

Submitted this as an issue on the sqm-scripts project page at: [[https://github.com/tohojo/sqm-scripts/issues/69]]

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