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#1726 - Netifd lockup when USB LTE modem was restarted #8488

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

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

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

Comments

@openwrt-bot
Copy link

svpcom:

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.

@openwrt-bot
Copy link
Author

ThomasCr:

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

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