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
Labels
Comments
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 than on 02:55:22 I executed echo 'at^reset' > /dev/ttyUSB0 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
svpcom:
OpenWrt 18.06.0, r7188-b0b5c64c22
Device: Xiaomi Mir3G
Modem: Huawei E3372
How to reproduce:
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
orifdown wwan
doesn't do anything until restart of netifd process.The text was updated successfully, but these errors were encountered: