Question

Hi,

I'm running RUT9_R_00.07.03 on a RUT950. The problem is that the modem driver hangs and connection is lost. I'm thinking this happens when LTE connectivity is temporarily bad. Sometimes this happens a dozen times a day, sometimes only once a week. The modem does not seem to recover, a full reboot is required. The error is seen in system log (see below for full log) as:

qmi_wwan 1-1:1.4: nonzero urb status received: -71

Note: The same issue happened with RUT9XX_R_00.06.09.2, there the error was indicated as:

option1 ttyUSB2: option_instat_callback: error -71

Is this a driver issue? Can it be cured somehow? Here is full system log from such an incident:

Fri Dec 30 07:35:35 2022 daemon.info gsmd[2490]: [check_req_timeout:104] error: [MODEM_MANAGER] Warning: request reached timeout (11s) on `1-1 [2c7c:XXXX]` modem!
Fri Dec 30 07:35:35 2022 daemon.notice netifd: mob1s1a1 (4213): Request timed out
Fri Dec 30 07:35:35 2022 daemon.notice netifd: mob1s1a1 (4213): Mobile connection lost!
Fri Dec 30 07:35:35 2022 daemon.notice netifd: Interface 'mob1s1a1' has lost the connection
Fri Dec 30 07:35:36 2022 daemon.notice netifd: mob1s1a1 (867): Stopping network mob1s1a1
Fri Dec 30 07:35:36 2022 daemon.notice netifd: mob1s1a1 (867): Stopping network on /dev/cdc-wdm0!
Fri Dec 30 07:35:36 2022 user.notice netifd: uqmi -s -d /dev/cdc-wdm0 -t 3000 --set-client-id wds,7 --stop-network 0xFFFFFFFF --autoconnect
Fri Dec 30 07:35:37 2022 user.warn mwan3-hotplug[866]: hotplug called on mob1s1a1 before mwan3 has been set up
Fri Dec 30 07:35:39 2022 daemon.notice netifd: mob1s1a1 (867): Request timed out
Fri Dec 30 07:35:39 2022 daemon.notice netifd: mob1s1a1 (867): "Unknown error"
Fri Dec 30 07:35:39 2022 user.notice mobile.sh: "Unknown error"
Fri Dec 30 07:35:39 2022 daemon.notice netifd: mob1s1a1 (867): Releasing client-id 7 on /dev/cdc-wdm0
Fri Dec 30 07:35:39 2022 user.notice netifd: uqmi -s -d /dev/cdc-wdm0 -t 3000 --set-client-id wds,7 --release-client-id wds
Fri Dec 30 07:35:41 2022 daemon.info dnsmasq[4041]: read /etc/hosts - 4 addresses
Fri Dec 30 07:35:41 2022 daemon.info dnsmasq[4041]: read /tmp/hosts/dhcp.cfg01411c - 2 addresses
Fri Dec 30 07:35:41 2022 daemon.info dnsmasq-dhcp[4041]: read /etc/ethers - 0 addresses
Fri Dec 30 07:35:42 2022 kern.info Mobile data disconnected (internal modem)
Fri Dec 30 07:35:42 2022 daemon.notice netifd: mob1s1a1 (867): Request timed out
Fri Dec 30 07:35:43 2022 daemon.notice netifd: mob1s1a1 (867): Command failed: Not found
Fri Dec 30 07:35:43 2022 daemon.info mdcollectd: Retrying firewall rule entry (retry 1)
Fri Dec 30 07:35:43 2022 daemon.notice netifd: mob1s1a1 (867): Command failed: Permission denied
Fri Dec 30 07:35:43 2022 daemon.notice netifd: Interface 'mob1s1a1' is now down
Fri Dec 30 07:35:43 2022 daemon.notice netifd: Interface 'mob1s1a1' is setting up now
Fri Dec 30 07:35:43 2022 daemon.warn dnsmasq[4041]: no servers found in /tmp/resolv.conf.d/resolv.conf.auto, will retry
Fri Dec 30 07:35:43 2022 daemon.notice netifd: mob1s1a1_4 (4224): udhcpc: received SIGTERM
Fri Dec 30 07:35:43 2022 daemon.notice netifd: Interface 'mob1s1a1_4' is now down
Fri Dec 30 07:35:43 2022 daemon.notice netifd: Interface 'mob1s1a1_4' is disabled
Fri Dec 30 07:35:43 2022 daemon.notice netifd: Interface 'mob1s1a1_4' has link connectivity loss
Fri Dec 30 07:35:43 2022 daemon.notice netifd: mob1s1a1 (1326): wwan[1326] Using wwan usb device on bus 1-1
Fri Dec 30 07:35:44 2022 daemon.notice netifd: mob1s1a1 (1326): wwan[1326] Using proto:qmi_wwan device:/dev/cdc-wdm0 iface:wwan0 desc:Quectel EC25
Fri Dec 30 07:35:45 2022 user.warn mwan3-hotplug[1306]: hotplug called on mob1s1a1_4 before mwan3 has been set up
Fri Dec 30 07:35:45 2022 daemon.info gsmd[2490]: [check_req_timeout:104] error: [MODEM_MANAGER] Warning: request reached timeout (10s) on `1-1 [2c7c:XXXX]` modem!
Fri Dec 30 07:35:48 2022 daemon.info dnsmasq[4041]: read /etc/hosts - 4 addresses
Fri Dec 30 07:35:48 2022 daemon.info dnsmasq[4041]: read /tmp/hosts/dhcp.cfg01411c - 2 addresses
Fri Dec 30 07:35:48 2022 daemon.info dnsmasq-dhcp[4041]: read /etc/ethers - 0 addresses
Fri Dec 30 07:35:55 2022 daemon.info gsmd[2490]: [check_req_timeout:104] error: [MODEM_MANAGER] Warning: request reached timeout (10s) on `1-1 [2c7c:XXXX]` modem!
Fri Dec 30 07:36:10 2022 daemon.info gsmd[2490]: [check_req_timeout:104] error: [MODEM_MANAGER] Warning: request reached timeout (11s) on `1-1 [2c7c:XXXX]` modem!
Fri Dec 30 07:36:19 2022 daemon.notice netifd: mob1s1a1 (1326): Command failed: Request timed out
Fri Dec 30 07:36:19 2022 daemon.notice netifd: mob1s1a1 (1326): Failed to parse message data
Fri Dec 30 07:36:19 2022 daemon.notice netifd: mob1s1a1 (1326): WARNING: Variable 'list' does not exist or is not an array/object
Fri Dec 30 07:36:19 2022 daemon.notice netifd: mob1s1a1 (1326): Creating context with PDP: 1
Fri Dec 30 07:36:20 2022 daemon.info gsmd[2490]: [check_req_timeout:104] error: [MODEM_MANAGER] Warning: request reached timeout (10s) on `1-1 [2c7c:XXXX]` modem!
Fri Dec 30 07:36:30 2022 daemon.info gsmd[2490]: [check_req_timeout:104] error: [MODEM_MANAGER] Warning: request reached timeout (10s) on `1-1 [2c7c:XXXX]` modem!
Fri Dec 30 07:36:45 2022 daemon.info gsmd[2490]: [check_req_timeout:104] error: [MODEM_MANAGER] Warning: request reached timeout (11s) on `1-1 [2c7c:XXXX]` modem!
Fri Dec 30 07:36:49 2022 kern.info Mobile data connected (internal modem)
Fri Dec 30 07:36:50 2022 kern.info Joined  network (internal modem)
Fri Dec 30 07:36:50 2022 kern.info Connected to  operator (internal modem)
Fri Dec 30 07:36:53 2022 daemon.info dnsmasq[4041]: read /etc/hosts - 4 addresses
Fri Dec 30 07:36:53 2022 daemon.info dnsmasq[4041]: read /tmp/hosts/dhcp.cfg01411c - 2 addresses
Fri Dec 30 07:36:53 2022 daemon.info dnsmasq-dhcp[4041]: read /etc/ethers - 0 addresses
Fri Dec 30 07:36:55 2022 daemon.info gsmd[2490]: [check_req_timeout:104] error: [MODEM_MANAGER] Warning: request reached timeout (10s) on `1-1 [2c7c:XXXX]` modem!
Fri Dec 30 07:37:10 2022 daemon.info gsmd[2490]: [check_req_timeout:104] error: [MODEM_MANAGER] Warning: request reached timeout (11s) on `1-1 [2c7c:XXXX]` modem!
Fri Dec 30 07:37:51 2022 daemon.notice netifd: mob1s1a1 (1326): Quering active sim position
Fri Dec 30 07:37:54 2022 kern.err kernel: [ 9127.144444] qmi_wwan 1-1:1.4: nonzero urb status received: -71
Fri Dec 30 07:37:54 2022 kern.err kernel: [ 9127.150403] qmi_wwan 1-1:1.4: wdm_int_callback - 0 bytes
Fri Dec 30 07:37:57 2022 kern.err kernel: [ 9130.216442] qmi_wwan 1-1:1.4: nonzero urb status received: -71
Fri Dec 30 07:37:57 2022 kern.err kernel: [ 9130.222404] qmi_wwan 1-1:1.4: wdm_int_callback - 0 bytes
Fri Dec 30 07:38:01 2022 kern.err kernel: [ 9133.288443] qmi_wwan 1-1:1.4: nonzero urb status received: -71
Fri Dec 30 07:38:01 2022 kern.err kernel: [ 9133.294398] qmi_wwan 1-1:1.4: wdm_int_callback - 0 bytes
Fri Dec 30 07:38:01 2022 daemon.notice netifd: mob1s1a1 (1326): Request timed out
Fri Dec 30 07:38:06 2022 kern.err kernel: [ 9139.176442] qmi_wwan 1-1:1.4: nonzero urb status received: -71
Fri Dec 30 07:38:06 2022 kern.err kernel: [ 9139.182399] qmi_wwan 1-1:1.4: wdm_int_callback - 0 bytes
Fri Dec 30 07:38:09 2022 kern.err kernel: [ 9142.248443] qmi_wwan 1-1:1.4: nonzero urb status received: -71
Fri Dec 30 07:38:09 2022 kern.err kernel: [ 9142.254399] qmi_wwan 1-1:1.4: wdm_int_callback - 0 bytes
Fri Dec 30 07:38:13 2022 kern.err kernel: [ 9145.320442] qmi_wwan 1-1:1.4: nonzero urb status received: -71
Fri Dec 30 07:38:13 2022 kern.err kernel: [ 9145.326400] qmi_wwan 1-1:1.4: wdm_int_callback - 0 bytes
Fri Dec 30 07:38:13 2022 daemon.notice netifd: mob1s1a1 (1326): Request timed out
Fri Dec 30 07:38:18 2022 kern.err kernel: [ 9151.208449] qmi_wwan 1-1:1.4: nonzero urb status received: -71
Fri Dec 30 07:38:18 2022 kern.err kernel: [ 9151.214415] qmi_wwan 1-1:1.4: wdm_int_callback - 0 bytes
Fri Dec 30 07:38:21 2022 kern.err kernel: [ 9154.280444] qmi_wwan 1-1:1.4: nonzero urb status received: -71
Fri Dec 30 07:38:21 2022 kern.err kernel: [ 9154.286402] qmi_wwan 1-1:1.4: wdm_int_callback - 0 bytes
Fri Dec 30 07:38:25 2022 kern.err kernel: [ 9157.352443] qmi_wwan 1-1:1.4: nonzero urb status received: -71
Fri Dec 30 07:38:25 2022 kern.err kernel: [ 9157.358403] qmi_wwan 1-1:1.4: wdm_int_callback - 0 bytes
Fri Dec 30 07:38:25 2022 daemon.notice netifd: mob1s1a1 (1326): Request timed out
Fri Dec 30 07:38:27 2022 user.notice mobile.sh: Device not responding, restarting module
Fri Dec 30 07:38:28 2022 daemon.notice netifd: mob1s1a1 (1326): Set CFUN=1,1
Fri Dec 30 07:38:58 2022 daemon.notice netifd: mob1s1a1 (2208): Stopping network mob1s1a1
Fri Dec 30 07:38:58 2022 daemon.notice netifd: mob1s1a1 (2208): Command failed: Not found
Fri Dec 30 07:38:58 2022 daemon.notice netifd: mob1s1a1 (2208): Command failed: Not found
Fri Dec 30 07:38:58 2022 daemon.notice netifd: mob1s1a1 (2208): Command failed: Permission denied
Fri Dec 30 07:38:58 2022 daemon.notice netifd: Interface 'mob1s1a1' is now down
Fri Dec 30 07:38:58 2022 daemon.notice netifd: Interface 'mob1s1a1' is setting up now
Fri Dec 30 07:38:58 2022 daemon.notice netifd: mob1s1a1 (2229): wwan[2229] Using wwan usb device on bus 1-1
Fri Dec 30 07:38:59 2022 daemon.notice netifd: mob1s1a1 (2229): wwan[2229] Using proto:qmi_wwan device:/dev/cdc-wdm0 iface:wwan0 desc:Quectel EC25
Fri Dec 30 07:39:03 2022 daemon.info dnsmasq[4041]: read /etc/hosts - 4 addresses
Fri Dec 30 07:39:03 2022 daemon.info dnsmasq[4041]: read /tmp/hosts/dhcp.cfg01411c - 2 addresses
Fri Dec 30 07:39:03 2022 daemon.info dnsmasq-dhcp[4041]: read /etc/ethers - 0 addresses
Fri Dec 30 07:39:34 2022 daemon.notice netifd: mob1s1a1 (2229): Command failed: Request timed out
Fri Dec 30 07:39:34 2022 daemon.notice netifd: mob1s1a1 (2229): Failed to parse message data
Fri Dec 30 07:39:34 2022 daemon.notice netifd: mob1s1a1 (2229): WARNING: Variable 'list' does not exist or is not an array/object
Fri Dec 30 07:39:34 2022 daemon.notice netifd: mob1s1a1 (2229): Creating context with PDP: 1
Fri Dec 30 07:40:15 2022 daemon.info gsmd[2490]: [check_req_timeout:104] error: [MODEM_MANAGER] Warning: request reached timeout (181s) on `1-1 [2c7c:XXXX]` modem!

Answer 1

Hello,

It would be more helpful, if attach a complete troubleshoot file to your question. Please, replicate the issue, then access router's WebUI, go to System -> Administration -> Troubleshoot section and download troubleshoot file from there. 

Best regards,

Comments

Hi,

unfortunately it looks like generating troubleshoot file does not work: It never finishes generating the file, I waited for 10 min.

Is there some workaround to reset the modem, like there was in RutOS v6? Can I do it from command line via ssh?

BR,

Tuomo

---

Troubleshoot generation can be initiated via SSH as well, with the following command:

• troubleshoot.sh

And extracted using scp/WinSCP, as instructed here, from /tmp directory of the router.

The likely suggestion to follow would be a modem firmware update. Thus, if troubleshoot generation fails, I would like you to provide the output of the following command:

• gsmctl -wy

As a workaround you can try restarting connection from Status -> Network -> Mobile section in the WebUI, but I assume this did not help.

Automatic modem reboot can be configured from Services -> Auto reboot -> Ping/Wget reboot section.

From SSH you can execute command below to reload modem instance:

• ubus -t 180 call mobifd.modem0 reload

Best regards,

---

Thanks, I'll try to run troubleshoot.sh next time, if it works. Yes, Restart Connection in WebUI did not help.

gsmctl -wy gave as result:

Quectel
EC25ECGAR06A08M1G_01.001.01.001

Are there instructions for updating modem firmware?

BR,
Tuomo

---

You can find instructions in this thread.

Keep in mind, that you will need a stable secondary WAN source and that update might not solve the issue, in which case, I would suggest to return the device for warranty repair or replacement.

Best regards,

---

Hi,

I installed the packages mentioned in that modem update thread you linked to, and run modem_updater -g. Do I interpret this result correctly: there are no updates available for this modem EC25ECGAR06A08M1G? Here's the output:

[INFO] Detected modem_n: 0
Modem List:
[0] Quectel EC25-EC | USB_ID: 1-1 | Firmware: EC25ECGAR06A08M1G_01.001.01.001 | builtin:true
Available versions:
EC25ECGAR06A04M1G
EC25ECGAR06A05M1G_01.005.01.005
EC25ECGAR06A07M1G_01.006.01.006
EC25ECGAR06A08M1G_01.001.01.001
EC25ECGAR06A09M1G_01.001.01.001
EC25ECGAR06A12M1G_20.002.20.002
EC25ECGAR06A13M1G_20.002.20.002
 

---

The modem_updater -g command simply lists available modem versions.

What you need to do next is enter the following command to initiate the update:

• modem_updater -i 1-1 -v EC25ECGAR06A13M1G_20.002.20.002

---

FYI I installed the updated modem firmware and also bought a Yagi antenna to boost the signal strength. So far no more crashing for a week! I think the modem firmware update got rid of the hanging, thanks for advice!

PS. Teltonika web interface did not detect that a modem firmware update was available, but modem_updater -g did.

BR,
Tuomo

---

Good to hear that the suggestion helped to resolve your case.

Best regards,