Netifd lockup when USB LTE modem was restarted
Username: Vasily Evseenko
Origin: https://bugs.openwrt.org/index.php?do=details&task_id=1726
OpenWrt 18.06.0, r7188-b0b5c64c
Device: Xiaomi Mir3G
Modem: Huawei E3372
How to reproduce:
-
Bring up wwan interface
-
Reconnect modem (or echo ‘at^reset’ > /dev/ttyUSB0)
-
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.