OpenWrt/LEDE Project

Attached to Project: OpenWrt/LEDE Project
Opened by Vasily Evseenko - 03.08.2018

FS#1726 - Netifd lockup when USB LTE modem was restarted

OpenWrt 18.06.0, r7188-b0b5c64c22
Device: Xiaomi Mir3G
Modem: Huawei E3372

How to reproduce:
1. Bring up wwan interface
2. Reconnect modem (or echo ‘at^reset’ > /dev/ttyUSB0)
3. See in logs:


Fri Aug 3 16:59:02 2018 kern.info kernel: [ 950.710901] usb 1-1: USB disconnect, device number 3
Fri Aug 3 16:59:02 2018 kern.info kernel: [ 950.716646] option1 ttyUSB0: GSM modem (1-port) converter now disconnected from ttyUSB0
Fri Aug 3 16:59:02 2018 kern.info kernel: [ 950.725206] option 1-1:1.0: device disconnected
Fri Aug 3 16:59:02 2018 kern.info kernel: [ 950.730738] option1 ttyUSB1: GSM modem (1-port) converter now disconnected from ttyUSB1
Fri Aug 3 16:59:02 2018 kern.info kernel: [ 950.739145] option 1-1:1.1: device disconnected
Fri Aug 3 16:59:02 2018 kern.info kernel: [ 950.744808] huawei_cdc_ncm 1-1:1.2 wwan0: unregister ‘huawei_cdc_ncm’ usb-1e1c0000.xhci-1, Huawei CDC NCM device
Fri Aug 3 16:59:02 2018 daemon.notice netifd: Network device ‘wwan0’ link is down
Fri Aug 3 16:59:02 2018 daemon.notice netifd: Network alias ‘wwan0’ link is down
Fri Aug 3 16:59:02 2018 daemon.notice netifd: Interface ‘wwan_4’ has link connectivity loss
Fri Aug 3 16:59:02 2018 daemon.notice netifd: Interface ‘wwan_4’ is disabled
Fri Aug 3 16:59:02 2018 daemon.info chronyd[1303]: Source 94.247.111.10 offline
Fri Aug 3 16:59:02 2018 daemon.info chronyd[1303]: Source 195.91.239.8 offline
Fri Aug 3 16:59:02 2018 daemon.info chronyd[1303]: Source 91.207.136.50 offline
Fri Aug 3 16:59:02 2018 daemon.info chronyd[1303]: Source 195.210.189.106 offline
Fri Aug 3 16:59:02 2018 daemon.info chronyd[1303]: Can’t synchronise: no selectable sources
Fri Aug 3 16:59:02 2018 daemon.notice netifd: wwan_4 (4421): udhcpc: SIOCGIFINDEX: No such device
Fri Aug 3 16:59:02 2018 daemon.notice netifd: wwan_4 (4421): udhcpc: received SIGTERM
Fri Aug 3 16:59:03 2018 daemon.notice netifd: wwan (4800): Stopping network wwan
Fri Aug 3 16:59:03 2018 daemon.notice netifd: wwan (4800): Can’t open device /dev/ttyUSB1.
Fri Aug 3 16:59:03 2018 daemon.notice netifd: wwan (4800): Failed to disconnect
Fri Aug 3 16:59:03 2018 daemon.notice netifd: Interface ‘wwan’ is now down
Fri Aug 3 16:59:03 2018 daemon.warn dnsmasq[4567]: no servers found in /tmp/resolv.conf.auto, will retry
Fri Aug 3 16:59:09 2018 kern.info kernel: [ 957.683961] usb 1-1: new high-speed USB device number 4 using xhci-mtk
Fri Aug 3 16:59:09 2018 kern.info kernel: [ 957.867590] option 1-1:1.0: GSM modem (1-port) converter detected
Fri Aug 3 16:59:09 2018 kern.info kernel: [ 957.874380] usb 1-1: GSM modem (1-port) converter now attached to ttyUSB0
Fri Aug 3 16:59:09 2018 kern.info kernel: [ 957.882334] option 1-1:1.1: GSM modem (1-port) converter detected
Fri Aug 3 16:59:09 2018 kern.info kernel: [ 957.889115] usb 1-1: GSM modem (1-port) converter now attached to ttyUSB1
Fri Aug 3 16:59:09 2018 kern.info kernel: [ 957.915707] huawei_cdc_ncm 1-1:1.2: resetting NTB format to 16-bit
Fri Aug 3 16:59:09 2018 kern.info kernel: [ 957.926799] huawei_cdc_ncm 1-1:1.2: MAC-Address: 00:1e:10:1f:00:00
Fri Aug 3 16:59:09 2018 kern.info kernel: [ 957.933038] huawei_cdc_ncm 1-1:1.2: setting rx_max = 16384
Fri Aug 3 16:59:09 2018 kern.info kernel: [ 957.938957] huawei_cdc_ncm 1-1:1.2: NDP will be placed at end of frame for this device.
Fri Aug 3 16:59:09 2018 kern.info kernel: [ 957.947454] huawei_cdc_ncm 1-1:1.2: cdc-wdm0: USB WDM device
Fri Aug 3 16:59:09 2018 kern.info kernel: [ 957.955970] huawei_cdc_ncm 1-1:1.2 wwan0: register ‘huawei_cdc_ncm’ at usb-1e1c0000.xhci-1, Huawei CDC NCM device, 00:1e:10:1f:00:00
Fri Aug 3 16:59:10 2018 daemon.notice netifd: Interface ‘wwan’ is setting up now
Fri Aug 3 16:59:10 2018 daemon.notice netifd: wwan (5197): Stopping network wwan
Fri Aug 3 16:59:11 2018 daemon.notice netifd: wwan (5197): sending → Fri Aug 3 16:59:15 2018 daemon.notice netifd: Interface ‘wwan’ is now down


Now ``ifup wwan`` or ``ifdown wwan`` doesn’t do anything until restart of netifd process.

ThomasCr commented on 01.09.2018 01:05

I want to share my output - for me it was working:

root@gw2:~# logread | grep -v pppoe | grep -v iplog | grep -e wwan -e tty -e ncm -e usb -e mwan -e option
Fri Aug 31 16:06:55 2018 kern.info kernel: [    4.836228] usbcore: registered new interface driver usbfs
Fri Aug 31 16:06:55 2018 kern.info kernel: [    4.841921] usbcore: registered new interface driver hub
Fri Aug 31 16:06:55 2018 kern.info kernel: [    4.847377] usbcore: registered new device driver usb
Fri Aug 31 16:06:55 2018 kern.info kernel: [   10.208904] usb 1-1: new high-speed USB device number 2 using ehci-platform
Fri Aug 31 16:06:55 2018 kern.info kernel: [   11.916407] usb 1-1: USB disconnect, device number 2
Fri Aug 31 16:06:55 2018 kern.info kernel: [   12.398134] usb 1-1: new high-speed USB device number 3 using ehci-platform
Fri Aug 31 16:06:55 2018 kern.info kernel: [   14.434888] usbcore: registered new interface driver cdc_wdm
Fri Aug 31 16:06:55 2018 kern.info kernel: [   14.814844] usbcore: registered new interface driver usbserial
Fri Aug 31 16:06:55 2018 kern.info kernel: [   14.820884] usbcore: registered new interface driver usbserial_generic
Fri Aug 31 16:06:55 2018 kern.info kernel: [   14.827575] usbserial: USB Serial support registered for generic
Fri Aug 31 16:06:55 2018 kern.info kernel: [   14.935628] usbcore: registered new interface driver cdc_ncm
Fri Aug 31 16:06:55 2018 kern.info kernel: [   15.139390] huawei_cdc_ncm 1-1:1.2: MAC-Address: 0c:5b:8f:27:9a:64
Fri Aug 31 16:06:55 2018 kern.info kernel: [   15.145670] huawei_cdc_ncm 1-1:1.2: setting rx_max = 16384
Fri Aug 31 16:06:55 2018 kern.info kernel: [   15.166304] huawei_cdc_ncm 1-1:1.2: setting tx_max = 16384
Fri Aug 31 16:06:55 2018 kern.info kernel: [   15.176347] huawei_cdc_ncm 1-1:1.2: NDP will be placed at end of frame for this device.
Fri Aug 31 16:06:55 2018 kern.info kernel: [   15.184722] huawei_cdc_ncm 1-1:1.2: cdc-wdm0: USB WDM device
Fri Aug 31 16:06:55 2018 kern.info kernel: [   15.191382] huawei_cdc_ncm 1-1:1.2 wwan0: register 'huawei_cdc_ncm' at usb-ehci-platform.0-1, Huawei CDC NCM device, 0c:5b:8f:27:9a:64
Fri Aug 31 16:06:55 2018 kern.info kernel: [   15.203814] usbcore: registered new interface driver huawei_cdc_ncm
Fri Aug 31 16:06:55 2018 kern.info kernel: [   20.411264] usbcore: registered new interface driver option
Fri Aug 31 16:06:55 2018 kern.info kernel: [   20.416996] usbserial: USB Serial support registered for GSM modem (1-port)
Fri Aug 31 16:06:55 2018 kern.info kernel: [   20.424418] option 1-1:1.0: GSM modem (1-port) converter detected
Fri Aug 31 16:06:55 2018 kern.info kernel: [   20.430861] usb 1-1: GSM modem (1-port) converter now attached to ttyUSB0
Fri Aug 31 16:06:55 2018 kern.info kernel: [   20.437830] option 1-1:1.1: GSM modem (1-port) converter detected
Fri Aug 31 16:06:55 2018 kern.info kernel: [   20.444276] usb 1-1: GSM modem (1-port) converter now attached to ttyUSB1
Fri Aug 31 16:06:57 2018 daemon.info dnsmasq[1473]: 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
Fri Aug 31 16:07:04 2018 user.notice ucitrack: Setting up /etc/config/mwan3 reload trigger for non-procd /etc/init.d/mwan3
Fri Aug 31 16:07:06 2018 daemon.notice netifd: Interface 'wwan' is setting up now
Fri Aug 31 16:07:08 2018 user.notice mwan3[1844]: Using firewall mask 0xff00
Fri Aug 31 16:07:08 2018 user.notice mwan3[1844]: Max interface count is 252
Sat Sep  1 02:38:09 2018 daemon.notice openvpn(Client)[1808]: OPTIONS IMPORT: --ifconfig/up options modified
Sat Sep  1 02:38:09 2018 daemon.notice openvpn(Client)[1808]: OPTIONS IMPORT: route options modified
Sat Sep  1 02:38:09 2018 daemon.notice openvpn(Client)[1808]: OPTIONS IMPORT: data channel crypto options modified
Sat Sep  1 02:38:09 2018 daemon.warn openvpn(Client)[1808]: WARNING: this configuration may cache passwords in memory -- use the auth-nocache option to prevent this
Sat Sep  1 02:38:24 2018 daemon.notice netifd: wwan (1852): sending -> AT
Sat Sep  1 02:38:25 2018 daemon.notice netifd: wwan (1852): sending -> ATZ
Sat Sep  1 02:38:25 2018 daemon.notice netifd: wwan (1852): sending -> ATQ0
Sat Sep  1 02:38:26 2018 daemon.notice netifd: wwan (1852): sending -> ATV1
Sat Sep  1 02:38:27 2018 daemon.notice netifd: wwan (1852): sending -> ATE1
Sat Sep  1 02:38:27 2018 daemon.notice netifd: wwan (1852): sending -> ATS0=0
Sat Sep  1 02:38:28 2018 daemon.notice netifd: wwan (1852): sending -> AT+CGDCONT=1,"IP","internet.t-mobile"
Sat Sep  1 02:38:31 2018 daemon.notice netifd: wwan (1852): Trying to set PIN
Sat Sep  1 02:38:32 2018 daemon.notice netifd: wwan (1852): PIN set successfully
Sat Sep  1 02:38:32 2018 daemon.notice netifd: wwan (1852): Configuring modem
Sat Sep  1 02:38:32 2018 daemon.notice netifd: wwan (1852): Setting mode
Sat Sep  1 02:38:33 2018 daemon.notice netifd: wwan (1852): sending -> AT^SYSCFGEX="00",3fffffff,2,4,7fffffffffffffff,,
Sat Sep  1 02:38:34 2018 daemon.notice netifd: wwan (1852): Starting network wwan
Sat Sep  1 02:38:34 2018 daemon.notice netifd: wwan (1852): Connecting modem
Sat Sep  1 02:38:34 2018 daemon.notice netifd: wwan (1852): sending -> AT^NDISDUP=1,1,"internet.t-mobile","telekom","telekom"
Sat Sep  1 02:38:36 2018 daemon.notice netifd: wwan (1852): Setting up wwan0
Sat Sep  1 02:38:36 2018 daemon.notice netifd: Interface 'wwan' is now up
Sat Sep  1 02:38:36 2018 daemon.notice netifd: Network device 'wwan0' link is up
Sat Sep  1 02:38:36 2018 daemon.notice netifd: Network alias 'wwan0' link is up
Sat Sep  1 02:38:36 2018 daemon.notice netifd: Interface 'wwan_4' is enabled
Sat Sep  1 02:38:36 2018 daemon.notice netifd: Interface 'wwan_4' has link connectivity
Sat Sep  1 02:38:36 2018 daemon.notice netifd: Interface 'wwan_4' is setting up now
Sat Sep  1 02:38:36 2018 daemon.notice netifd: wwan_4 (3308): udhcpc: started, v1.28.3
Sat Sep  1 02:38:37 2018 daemon.notice netifd: wwan_4 (3308): udhcpc: sending discover
Sat Sep  1 02:38:37 2018 daemon.notice netifd: wwan_4 (3308): udhcpc: sending select for 10.159.152.204
Sat Sep  1 02:38:37 2018 daemon.notice netifd: wwan_4 (3308): udhcpc: lease of 10.159.152.204 obtained, lease time 518400
Sat Sep  1 02:38:37 2018 daemon.notice netifd: Interface 'wwan_4' is now up
Sat Sep  1 02:38:38 2018 user.notice mwan3[3287]: Execute ifup event on interface wwan (wwan0)
Sat Sep  1 02:38:39 2018 user.notice mwan3[3287]: Starting tracker on interface wwan (wwan0)
Sat Sep  1 02:38:39 2018 user.info mwan3track[3553]: Check (ping) success for target "www-proxy.t-online.de" on interface wwan (wwan0)
Sat Sep  1 02:38:39 2018 user.notice firewall: Reloading firewall due to ifup of wwan (wwan0)
Sat Sep  1 02:38:44 2018 user.info mwan3track[3553]: Check (ping) success for target "www-proxy.t-online.de" on interface wwan (wwan0)
Sat Sep  1 02:38:49 2018 user.info mwan3track[3553]: Check (ping) success for target "www-proxy.t-online.de" on interface wwan (wwan0)
Sat Sep  1 02:38:54 2018 user.info mwan3track[3553]: Check (ping) success for target "www-proxy.t-online.de" on interface wwan (wwan0)
Sat Sep  1 02:38:59 2018 user.info mwan3track[3553]: Check (ping) success for target "www-proxy.t-online.de" on interface wwan (wwan0)
Sat Sep  1 02:39:04 2018 user.info mwan3track[3553]: Check (ping) success for target "www-proxy.t-online.de" on interface wwan (wwan0)
Sat Sep  1 02:39:09 2018 user.info mwan3track[3553]: Check (ping) success for target "www-proxy.t-online.de" on interface wwan (wwan0)
Sat Sep  1 02:39:15 2018 user.info mwan3track[3553]: Check (ping) success for target "www-proxy.t-online.de" on interface wwan (wwan0)
Sat Sep  1 02:39:15 2018 user.notice mwan3track[3553]: Interface wwan (wwan0) is online
Sat Sep  1 02:39:16 2018 user.notice mwan3[4344]: Execute ifup event on interface wwan (wwan0)
Sat Sep  1 02:39:18 2018 user.notice mwan3[4344]: Starting tracker on interface wwan (wwan0)
Sat Sep  1 02:39:19 2018 user.info mwan3[4344]: connection tracking not flushed on interface wwan (wwan0) ifup
Sat Sep  1 02:39:20 2018 user.notice firewall: Reloading firewall due to ifup of wwan (wwan0)
Sat Sep  1 02:49:35 2018 daemon.notice netifd: wwan (6652): Stopping network wwan
Sat Sep  1 02:49:35 2018 daemon.notice netifd: wwan_4 (3308): udhcpc: received SIGTERM
Sat Sep  1 02:49:35 2018 daemon.notice netifd: Interface 'wwan_4' is now down
Sat Sep  1 02:49:35 2018 daemon.notice netifd: Interface 'wwan_4' has link connectivity loss
Sat Sep  1 02:49:35 2018 daemon.notice netifd: Interface 'wwan_4' is disabled
Sat Sep  1 02:49:36 2018 daemon.notice netifd: wwan (6652): sending -> AT^NDISDUP=1,0
Sat Sep  1 02:49:36 2018 daemon.notice netifd: wwan (6652): Command failed: Permission denied
Sat Sep  1 02:49:36 2018 daemon.notice netifd: Interface 'wwan' is now down
Sat Sep  1 02:49:37 2018 user.notice mwan3[6650]: Execute ifdown event on interface wwan (unknown)
Sat Sep  1 02:49:37 2018 user.info mwan3track[4554]: Detect ifdown event on interface wwan (wwan0)
Sat Sep  1 02:49:37 2018 user.info mwan3[6650]: connection tracking not flushed on interface wwan (ifdown)
Sat Sep  1 02:49:39 2018 daemon.notice netifd: Interface 'wwan' is setting up now
Sat Sep  1 02:50:12 2018 daemon.notice netifd: wwan (6930): sending -> AT
Sat Sep  1 02:50:12 2018 daemon.notice netifd: wwan (6930): sending -> ATZ
Sat Sep  1 02:50:13 2018 daemon.notice netifd: wwan (6930): sending -> ATQ0
Sat Sep  1 02:50:14 2018 daemon.notice netifd: wwan (6930): sending -> ATV1
Sat Sep  1 02:50:14 2018 daemon.notice netifd: wwan (6930): sending -> ATE1
Sat Sep  1 02:50:15 2018 daemon.notice netifd: wwan (6930): sending -> ATS0=0
Sat Sep  1 02:50:16 2018 daemon.notice netifd: wwan (6930): sending -> AT+CGDCONT=1,"IP","internet.t-mobile"
Sat Sep  1 02:50:18 2018 daemon.notice netifd: wwan (6930): SIM ready
Sat Sep  1 02:50:18 2018 daemon.notice netifd: wwan (6930): PIN set successfully
Sat Sep  1 02:50:18 2018 daemon.notice netifd: wwan (6930): Configuring modem
Sat Sep  1 02:50:18 2018 daemon.notice netifd: wwan (6930): Setting mode
Sat Sep  1 02:50:19 2018 daemon.notice netifd: wwan (6930): sending -> AT^SYSCFGEX="00",3fffffff,2,4,7fffffffffffffff,,
Sat Sep  1 02:50:20 2018 daemon.notice netifd: wwan (6930): Starting network wwan
Sat Sep  1 02:50:20 2018 daemon.notice netifd: wwan (6930): Connecting modem
Sat Sep  1 02:50:20 2018 daemon.notice netifd: wwan (6930): sending -> AT^NDISDUP=1,1,"internet.t-mobile","telekom","telekom"
Sat Sep  1 02:50:21 2018 daemon.notice netifd: wwan (6930): Setting up wwan0
Sat Sep  1 02:50:21 2018 daemon.notice netifd: Interface 'wwan' is now up
Sat Sep  1 02:50:21 2018 daemon.notice netifd: Network device 'wwan0' link is up
Sat Sep  1 02:50:22 2018 daemon.notice netifd: Network alias 'wwan0' link is up
Sat Sep  1 02:50:22 2018 daemon.notice netifd: Interface 'wwan_4' is enabled
Sat Sep  1 02:50:22 2018 daemon.notice netifd: Interface 'wwan_4' has link connectivity
Sat Sep  1 02:50:22 2018 daemon.notice netifd: Interface 'wwan_4' is setting up now
Sat Sep  1 02:50:22 2018 daemon.notice netifd: wwan_4 (7130): udhcpc: started, v1.28.3
Sat Sep  1 02:50:22 2018 daemon.notice netifd: wwan_4 (7130): udhcpc: sending discover
Sat Sep  1 02:50:22 2018 daemon.notice netifd: wwan_4 (7130): udhcpc: sending select for 10.198.225.69
Sat Sep  1 02:50:22 2018 daemon.notice netifd: wwan_4 (7130): udhcpc: lease of 10.198.225.69 obtained, lease time 518400
Sat Sep  1 02:50:22 2018 daemon.notice netifd: Interface 'wwan_4' is now up
Sat Sep  1 02:50:23 2018 user.notice mwan3[7110]: Execute ifup event on interface wwan (wwan0)
Sat Sep  1 02:50:24 2018 user.notice mwan3[7110]: Starting tracker on interface wwan (wwan0)
Sat Sep  1 02:50:24 2018 user.notice mwan3track[4554]: Stopping mwan3track for interface "wwan"
Sat Sep  1 02:50:25 2018 user.info mwan3track[7378]: Check (ping) success for target "www-proxy.t-online.de" on interface wwan (wwan0)
Sat Sep  1 02:50:26 2018 user.notice firewall: Reloading firewall due to ifup of wwan (wwan0)
Sat Sep  1 02:50:30 2018 user.info mwan3track[7378]: Check (ping) success for target "www-proxy.t-online.de" on interface wwan (wwan0)
Sat Sep  1 02:50:36 2018 user.info mwan3track[7378]: Check (ping) success for target "www-proxy.t-online.de" on interface wwan (wwan0)
Sat Sep  1 02:50:41 2018 user.info mwan3track[7378]: Check (ping) success for target "www-proxy.t-online.de" on interface wwan (wwan0)
Sat Sep  1 02:50:46 2018 user.info mwan3track[7378]: Check (ping) success for target "www-proxy.t-online.de" on interface wwan (wwan0)
Sat Sep  1 02:50:51 2018 user.info mwan3track[7378]: Check (ping) success for target "www-proxy.t-online.de" on interface wwan (wwan0)
Sat Sep  1 02:50:56 2018 user.info mwan3track[7378]: Check (ping) success for target "www-proxy.t-online.de" on interface wwan (wwan0)
Sat Sep  1 02:51:01 2018 user.info mwan3track[7378]: Check (ping) success for target "www-proxy.t-online.de" on interface wwan (wwan0)
Sat Sep  1 02:51:01 2018 user.notice mwan3track[7378]: Interface wwan (wwan0) is online
Sat Sep  1 02:51:02 2018 user.notice mwan3[7755]: Execute ifup event on interface wwan (wwan0)
Sat Sep  1 02:51:03 2018 user.notice mwan3[7755]: Starting tracker on interface wwan (wwan0)
Sat Sep  1 02:51:05 2018 user.info mwan3[7755]: connection tracking not flushed on interface wwan (wwan0) ifup
Sat Sep  1 02:51:05 2018 user.notice firewall: Reloading firewall due to ifup of wwan (wwan0)
Sat Sep  1 02:55:22 2018 kern.info kernel: [ 1084.178116] usb 1-1: USB disconnect, device number 3
Sat Sep  1 02:55:22 2018 kern.info kernel: [ 1084.183499] option1 ttyUSB0: GSM modem (1-port) converter now disconnected from ttyUSB0
Sat Sep  1 02:55:22 2018 kern.info kernel: [ 1084.191780] option 1-1:1.0: device disconnected
Sat Sep  1 02:55:22 2018 kern.info kernel: [ 1084.196694] option1 ttyUSB1: GSM modem (1-port) converter now disconnected from ttyUSB1
Sat Sep  1 02:55:22 2018 kern.info kernel: [ 1084.204947] option 1-1:1.1: device disconnected
Sat Sep  1 02:55:22 2018 kern.info kernel: [ 1084.209833] huawei_cdc_ncm 1-1:1.2 wwan0: unregister 'huawei_cdc_ncm' usb-ehci-platform.0-1, Huawei CDC NCM device
Sat Sep  1 02:55:22 2018 daemon.notice netifd: Network device 'wwan0' link is down
Sat Sep  1 02:55:22 2018 daemon.notice netifd: Network alias 'wwan0' link is down
Sat Sep  1 02:55:22 2018 daemon.notice netifd: Interface 'wwan_4' has link connectivity loss
Sat Sep  1 02:55:22 2018 daemon.notice netifd: Interface 'wwan_4' is disabled
Sat Sep  1 02:55:23 2018 daemon.notice netifd: wwan_4 (7130): udhcpc: SIOCGIFINDEX: No such device
Sat Sep  1 02:55:23 2018 daemon.notice netifd: wwan_4 (7130): udhcpc: received SIGTERM
Sat Sep  1 02:55:23 2018 user.info mwan3track[7959]: Check (ping) failed for target "www-proxy.t-online.de" on interface wwan (wwan0)
Sat Sep  1 02:55:24 2018 user.info mwan3track[7959]: Check (ping) failed for target "8.8.8.8" on interface wwan (wwan0)
Sat Sep  1 02:55:25 2018 user.info mwan3track[7959]: Check (ping) failed for target "8.8.4.4" on interface wwan (wwan0)
Sat Sep  1 02:55:25 2018 daemon.notice netifd: wwan (9254): Stopping network wwan
Sat Sep  1 02:55:26 2018 daemon.notice netifd: wwan (9254): Can't open device /dev/ttyUSB1.
Sat Sep  1 02:55:26 2018 daemon.notice netifd: wwan (9254): Failed to disconnect
Sat Sep  1 02:55:26 2018 daemon.notice netifd: Interface 'wwan' is now down
Sat Sep  1 02:55:30 2018 user.info mwan3track[7959]: Check (ping) failed for target "www-proxy.t-online.de" on interface wwan (wwan0)
Sat Sep  1 02:55:31 2018 user.info mwan3track[7959]: Check (ping) failed for target "8.8.8.8" on interface wwan (wwan0)
Sat Sep  1 02:55:31 2018 user.info mwan3track[7959]: Check (ping) failed for target "8.8.4.4" on interface wwan (wwan0)
Sat Sep  1 02:55:31 2018 user.notice mwan3[9401]: Execute ifdown event on interface wwan (unknown)
Sat Sep  1 02:55:31 2018 user.info mwan3track[7959]: Detect ifdown event on interface wwan (wwan0)
Sat Sep  1 02:55:32 2018 kern.info kernel: [ 1093.407810] usb 1-1: new high-speed USB device number 4 using ehci-platform
Sat Sep  1 02:55:32 2018 kern.info kernel: [ 1093.626080] option 1-1:1.0: GSM modem (1-port) converter detected
Sat Sep  1 02:55:32 2018 kern.info kernel: [ 1093.632561] usb 1-1: GSM modem (1-port) converter now attached to ttyUSB0
Sat Sep  1 02:55:32 2018 kern.info kernel: [ 1093.640035] option 1-1:1.1: GSM modem (1-port) converter detected
Sat Sep  1 02:55:32 2018 kern.info kernel: [ 1093.646474] usb 1-1: GSM modem (1-port) converter now attached to ttyUSB1
Sat Sep  1 02:55:32 2018 kern.info kernel: [ 1093.760368] huawei_cdc_ncm 1-1:1.2: MAC-Address: 0c:5b:8f:27:9a:64
Sat Sep  1 02:55:32 2018 kern.info kernel: [ 1093.766688] huawei_cdc_ncm 1-1:1.2: setting rx_max = 16384
Sat Sep  1 02:55:32 2018 kern.info kernel: [ 1093.791179] huawei_cdc_ncm 1-1:1.2: setting tx_max = 16384
Sat Sep  1 02:55:32 2018 kern.info kernel: [ 1093.821339] huawei_cdc_ncm 1-1:1.2: NDP will be placed at end of frame for this device.
Sat Sep  1 02:55:32 2018 kern.info kernel: [ 1093.830026] huawei_cdc_ncm 1-1:1.2: cdc-wdm0: USB WDM device
Sat Sep  1 02:55:32 2018 kern.info kernel: [ 1093.836708] huawei_cdc_ncm 1-1:1.2 wwan0: register 'huawei_cdc_ncm' at usb-ehci-platform.0-1, Huawei CDC NCM device, 0c:5b:8f:27:9a:64
Sat Sep  1 02:55:32 2018 user.info mwan3[9401]: connection tracking not flushed on interface wwan (ifdown)
Sat Sep  1 02:55:34 2018 daemon.notice netifd: Interface 'wwan' is setting up now
Sat Sep  1 02:56:06 2018 daemon.notice netifd: wwan (9845): sending -> AT
Sat Sep  1 02:56:07 2018 daemon.notice netifd: wwan (9845): sending -> ATZ
Sat Sep  1 02:56:07 2018 daemon.notice netifd: wwan (9845): sending -> ATQ0
Sat Sep  1 02:56:08 2018 daemon.notice netifd: wwan (9845): sending -> ATV1
Sat Sep  1 02:56:09 2018 daemon.notice netifd: wwan (9845): sending -> ATE1
Sat Sep  1 02:56:09 2018 daemon.notice netifd: wwan (9845): sending -> ATS0=0
Sat Sep  1 02:56:10 2018 daemon.notice netifd: wwan (9845): sending -> AT+CGDCONT=1,"IP","internet.t-mobile"
Sat Sep  1 02:56:13 2018 daemon.notice netifd: wwan (9845): Trying to set PIN
Sat Sep  1 02:56:15 2018 daemon.notice netifd: wwan (9845): PIN set successfully
Sat Sep  1 02:56:15 2018 daemon.notice netifd: wwan (9845): Configuring modem
Sat Sep  1 02:56:15 2018 daemon.notice netifd: wwan (9845): Setting mode
Sat Sep  1 02:56:15 2018 daemon.notice netifd: wwan (9845): sending -> AT^SYSCFGEX="00",3fffffff,2,4,7fffffffffffffff,,
Sat Sep  1 02:56:16 2018 daemon.notice netifd: wwan (9845): Starting network wwan
Sat Sep  1 02:56:16 2018 daemon.notice netifd: wwan (9845): Connecting modem
Sat Sep  1 02:56:17 2018 daemon.notice netifd: wwan (9845): sending -> AT^NDISDUP=1,1,"internet.t-mobile","telekom","telekom"
Sat Sep  1 02:56:18 2018 daemon.notice netifd: wwan (9845): Setting up wwan0
Sat Sep  1 02:56:18 2018 daemon.notice netifd: Interface 'wwan_4' is enabled
Sat Sep  1 02:56:18 2018 daemon.notice netifd: Interface 'wwan' is now up
Sat Sep  1 02:56:18 2018 daemon.notice netifd: Network device 'wwan0' link is up
Sat Sep  1 02:56:18 2018 daemon.notice netifd: Network alias 'wwan0' link is up
Sat Sep  1 02:56:18 2018 daemon.notice netifd: Interface 'wwan_4' has link connectivity
Sat Sep  1 02:56:18 2018 daemon.notice netifd: Interface 'wwan_4' is setting up now
Sat Sep  1 02:56:18 2018 daemon.notice netifd: wwan_4 (10154): udhcpc: started, v1.28.3
Sat Sep  1 02:56:18 2018 daemon.notice netifd: wwan_4 (10154): udhcpc: sending discover
Sat Sep  1 02:56:18 2018 daemon.notice netifd: wwan_4 (10154): udhcpc: sending select for 10.160.24.154
Sat Sep  1 02:56:18 2018 daemon.notice netifd: wwan_4 (10154): udhcpc: lease of 10.160.24.154 obtained, lease time 518400
Sat Sep  1 02:56:18 2018 daemon.notice netifd: Interface 'wwan_4' is now up
Sat Sep  1 02:56:19 2018 user.notice mwan3[10134]: Execute ifup event on interface wwan (wwan0)
Sat Sep  1 02:56:21 2018 user.notice mwan3[10134]: Starting tracker on interface wwan (wwan0)
Sat Sep  1 02:56:21 2018 user.notice mwan3track[7959]: Stopping mwan3track for interface "wwan"
Sat Sep  1 02:56:22 2018 user.info mwan3track[10407]: Check (ping) success for target "www-proxy.t-online.de" on interface wwan (wwan0)
Sat Sep  1 02:56:22 2018 user.notice firewall: Reloading firewall due to ifup of wwan (wwan0)
Sat Sep  1 02:56:27 2018 user.info mwan3track[10407]: Check (ping) success for target "www-proxy.t-online.de" on interface wwan (wwan0)
Sat Sep  1 02:56:33 2018 user.info mwan3track[10407]: Check (ping) success for target "www-proxy.t-online.de" on interface wwan (wwan0)
Sat Sep  1 02:56:38 2018 user.info mwan3track[10407]: Check (ping) success for target "www-proxy.t-online.de" on interface wwan (wwan0)
Sat Sep  1 02:56:43 2018 user.info mwan3track[10407]: Check (ping) success for target "www-proxy.t-online.de" on interface wwan (wwan0)
Sat Sep  1 02:56:48 2018 user.info mwan3track[10407]: Check (ping) success for target "www-proxy.t-online.de" on interface wwan (wwan0)
Sat Sep  1 02:56:53 2018 user.info mwan3track[10407]: Check (ping) success for target "www-proxy.t-online.de" on interface wwan (wwan0)
Sat Sep  1 02:56:58 2018 user.info mwan3track[10407]: Check (ping) success for target "www-proxy.t-online.de" on interface wwan (wwan0)
Sat Sep  1 02:56:58 2018 user.notice mwan3track[10407]: Interface wwan (wwan0) is online
Sat Sep  1 02:56:59 2018 user.notice mwan3[10785]: Execute ifup event on interface wwan (wwan0)
Sat Sep  1 02:56:59 2018 user.notice mwan3[10785]: Starting tracker on interface wwan (wwan0)
Sat Sep  1 02:57:02 2018 user.info mwan3[10785]: connection tracking not flushed on interface wwan (wwan0) ifup
Sat Sep  1 02:57:02 2018 user.notice firewall: Reloading firewall due to ifup of wwan (wwan0)

on 02:49:36 I executed ifdown wwan followed by ifup wwan
the sticks seems still connected

than on 02:55:22 I executed echo 'at^reset' > /dev/ttyUSB0
and than the stick reboots and comes up fine again.

device is a Archer C7 v2 running OpenWrt 18.06.1

Loading...

Available keyboard shortcuts

Tasklist

Task Details

Task Editing