Hello,
I was debugging an issue with no internet connection on RUTX12 and I think I found an undocumented “feature” at best but probably a bug:
One of our sim cards was deactivated by operator in a way that it connects, still gets an IP but has no access to internet whatsoever. Normally it wouldn’t be a problem as there a SIM in second slot and mwan3 configured as failover. However there are only very brief periods of internet from second modem and network seems to reload constantly. After some digging I found a mobifd deamon log in /tmp/mobile/mobifd.log which reveals that it pings predefined list of IPs and when this fails it restarts BOTH modems. This completely defeats mwan3 operation and in situation we encoutered renders backup modem pointless.
My questions are:
- Is restarting both modems a bug? If not, what is the reasoning behind it?
- Can this feature be disabled by some config option?
- Can IP list be customized?
I attach a relevant log fragment below:
[Thu Oct 17 06:22:17] Checking network.interface.'mob1s1a1' ubus ID
[Thu Oct 17 06:22:17] Interface 'mob1s1a1' is going up
[Thu Oct 17 06:22:17] Setting IFUP timeout for 'mob1s1a1' of "120s"
[Thu Oct 17 06:22:23] Network event received "ifup" for 'mob1s1a1_4'
[Thu Oct 17 06:22:24] Sending PING "qmimux0" -> "8.8.8.8"
[Thu Oct 17 06:22:24] Sending PING "qmimux0" -> "8.8.8.8"
[Thu Oct 17 06:22:25] Sending PING "qmimux0" -> "8.8.8.8"
[Thu Oct 17 06:22:26] Sending PING "qmimux0" -> "8.8.8.8"
[Thu Oct 17 06:22:27] Sending PING "qmimux0" -> "8.8.8.8"
[Thu Oct 17 06:22:28] PING to "8.8.8.8" failed
[Thu Oct 17 06:22:28] Sending PING "qmimux0" -> "1.1.1.1"
[Thu Oct 17 06:22:28] Sending PING "qmimux0" -> "1.1.1.1"
[Thu Oct 17 06:22:29] Sending PING "qmimux0" -> "1.1.1.1"
[Thu Oct 17 06:22:30] Sending PING "qmimux0" -> "1.1.1.1"
[Thu Oct 17 06:22:31] Sending PING "qmimux0" -> "1.1.1.1"
[Thu Oct 17 06:22:32] PING to "1.1.1.1" failed
[Thu Oct 17 06:22:32] Sending PING "qmimux0" -> "208.67.222.222"
[Thu Oct 17 06:22:32] Sending PING "qmimux0" -> "208.67.222.222"
[Thu Oct 17 06:22:33] Sending PING "qmimux0" -> "208.67.222.222"
[Thu Oct 17 06:22:34] Sending PING "qmimux0" -> "208.67.222.222"
[Thu Oct 17 06:22:35] Sending PING "qmimux0" -> "208.67.222.222"
[Thu Oct 17 06:22:36] PING to "208.67.222.222" failed
[Thu Oct 17 06:22:36] Sending PING "qmimux0" -> "9.9.9.9"
[Thu Oct 17 06:22:36] Sending PING "qmimux0" -> "9.9.9.9"
[Thu Oct 17 06:22:37] Sending PING "qmimux0" -> "9.9.9.9"
[Thu Oct 17 06:22:38] Sending PING "qmimux0" -> "9.9.9.9"
[Thu Oct 17 06:22:39] Sending PING "qmimux0" -> "9.9.9.9"
[Thu Oct 17 06:22:40] PING to "9.9.9.9" failed
[Thu Oct 17 06:22:40] Sending PING "qmimux0" -> "64.6.64.6"
[Thu Oct 17 06:22:40] Sending PING "qmimux0" -> "64.6.64.6"
[Thu Oct 17 06:22:41] Sending PING "qmimux0" -> "64.6.64.6"
[Thu Oct 17 06:22:42] Sending PING "qmimux0" -> "64.6.64.6"
[Thu Oct 17 06:22:43] Sending PING "qmimux0" -> "64.6.64.6"
[Thu Oct 17 06:22:44] PING to "64.6.64.6" failed
[Thu Oct 17 06:22:44] IPV4 ping failed for 'mob1s1a1'
[Thu Oct 17 06:22:44] Sending PING "qmimux0" -> "8.8.8.8"
[Thu Oct 17 06:22:45] Sending PING "qmimux0" -> "8.8.8.8"
[Thu Oct 17 06:22:46] Sending PING "qmimux0" -> "8.8.8.8"
[Thu Oct 17 06:22:46] Sending PING "qmimux0" -> "8.8.8.8"
[Thu Oct 17 06:22:47] Sending PING "qmimux0" -> "8.8.8.8"
[Thu Oct 17 06:22:48] PING to "8.8.8.8" failed
[Thu Oct 17 06:22:48] Sending PING "qmimux0" -> "1.1.1.1"
[Thu Oct 17 06:22:49] Sending PING "qmimux0" -> "1.1.1.1"
[Thu Oct 17 06:22:50] Sending PING "qmimux0" -> "1.1.1.1"
[Thu Oct 17 06:22:50] Sending PING "qmimux0" -> "1.1.1.1"
[Thu Oct 17 06:22:51] Sending PING "qmimux0" -> "1.1.1.1"
[Thu Oct 17 06:22:52] PING to "1.1.1.1" failed
[Thu Oct 17 06:22:52] Sending PING "qmimux0" -> "208.67.222.222"
[Thu Oct 17 06:22:53] Sending PING "qmimux0" -> "208.67.222.222"
[Thu Oct 17 06:22:54] Sending PING "qmimux0" -> "208.67.222.222"
[Thu Oct 17 06:22:54] Sending PING "qmimux0" -> "208.67.222.222"
[Thu Oct 17 06:22:55] Sending PING "qmimux0" -> "208.67.222.222"
[Thu Oct 17 06:22:56] PING to "208.67.222.222" failed
[Thu Oct 17 06:22:56] Sending PING "qmimux0" -> "9.9.9.9"
[Thu Oct 17 06:22:57] Sending PING "qmimux0" -> "9.9.9.9"
[Thu Oct 17 06:22:58] Sending PING "qmimux0" -> "9.9.9.9"
[Thu Oct 17 06:22:58] Sending PING "qmimux0" -> "9.9.9.9"
[Thu Oct 17 06:22:59] Sending PING "qmimux0" -> "9.9.9.9"
[Thu Oct 17 06:23:00] PING to "9.9.9.9" failed
[Thu Oct 17 06:23:00] Sending PING "qmimux0" -> "64.6.64.6"
[Thu Oct 17 06:23:01] Sending PING "qmimux0" -> "64.6.64.6"
[Thu Oct 17 06:23:02] Sending PING "qmimux0" -> "64.6.64.6"
[Thu Oct 17 06:23:02] Sending PING "qmimux0" -> "64.6.64.6"
[Thu Oct 17 06:23:03] Sending PING "qmimux0" -> "64.6.64.6"
[Thu Oct 17 06:23:04] PING to "64.6.64.6" failed
[Thu Oct 17 06:23:04] IPV4 ping failed for 'mob1s1a1'
[Thu Oct 17 06:23:04] Sending PING "qmimux0" -> "8.8.8.8"
[Thu Oct 17 06:23:05] Sending PING "qmimux0" -> "8.8.8.8"
[Thu Oct 17 06:23:06] Sending PING "qmimux0" -> "8.8.8.8"
[Thu Oct 17 06:23:07] Sending PING "qmimux0" -> "8.8.8.8"
[Thu Oct 17 06:23:08] Sending PING "qmimux0" -> "8.8.8.8"
[Thu Oct 17 06:23:08] PING to "8.8.8.8" failed
[Thu Oct 17 06:23:08] Sending PING "qmimux0" -> "1.1.1.1"
[Thu Oct 17 06:23:09] Sending PING "qmimux0" -> "1.1.1.1"
[Thu Oct 17 06:23:10] Sending PING "qmimux0" -> "1.1.1.1"
[Thu Oct 17 06:23:11] Sending PING "qmimux0" -> "1.1.1.1"
[Thu Oct 17 06:23:12] Sending PING "qmimux0" -> "1.1.1.1"
[Thu Oct 17 06:23:13] PING to "1.1.1.1" failed
[Thu Oct 17 06:23:13] Sending PING "qmimux0" -> "208.67.222.222"
[Thu Oct 17 06:23:13] Sending PING "qmimux0" -> "208.67.222.222"
[Thu Oct 17 06:23:14] Sending PING "qmimux0" -> "208.67.222.222"
[Thu Oct 17 06:23:15] Sending PING "qmimux0" -> "208.67.222.222"
[Thu Oct 17 06:23:16] Sending PING "qmimux0" -> "208.67.222.222"
[Thu Oct 17 06:23:17] PING to "208.67.222.222" failed
[Thu Oct 17 06:23:17] Sending PING "qmimux0" -> "9.9.9.9"
[Thu Oct 17 06:23:17] Sending PING "qmimux0" -> "9.9.9.9"
[Thu Oct 17 06:23:18] Sending PING "qmimux0" -> "9.9.9.9"
[Thu Oct 17 06:23:19] Sending PING "qmimux0" -> "9.9.9.9"
[Thu Oct 17 06:23:20] Sending PING "qmimux0" -> "9.9.9.9"
[Thu Oct 17 06:23:21] PING to "9.9.9.9" failed
[Thu Oct 17 06:23:21] Sending PING "qmimux0" -> "64.6.64.6"
[Thu Oct 17 06:23:21] Sending PING "qmimux0" -> "64.6.64.6"
[Thu Oct 17 06:23:22] Sending PING "qmimux0" -> "64.6.64.6"
[Thu Oct 17 06:23:23] Sending PING "qmimux0" -> "64.6.64.6"
[Thu Oct 17 06:23:24] Sending PING "qmimux0" -> "64.6.64.6"
[Thu Oct 17 06:23:25] PING to "64.6.64.6" failed
[Thu Oct 17 06:23:25] IPV4 ping failed for 'mob1s1a1'
[Thu Oct 17 06:23:25] Checking network.interface.'mob1s1a1' ubus ID
[Thu Oct 17 06:23:25] Interface 'mob1s1a1' going down
[Thu Oct 17 06:23:25] Network event received "ifup" for 'mob1s1a1_4'
[Thu Oct 17 06:23:25] Attribute "l3_device" was not found in 'status' response
[Thu Oct 17 06:23:25] Unable to get l3_device for 'mob1s1a1'
[Thu Oct 17 06:23:25] IPV4 ping failed for 'mob1s1a1'
[Thu Oct 17 06:23:25] Unable to find UBUS object 'network.interface.mob1s1a1_4'
[Thu Oct 17 06:23:25] Unable to get l3_device for 'mob1s1a1'
[Thu Oct 17 06:23:25] IPV4 ping failed for 'mob1s1a1'
[Thu Oct 17 06:23:26] Unable to find UBUS object 'network.interface.mob1s1a1_4'
[Thu Oct 17 06:23:26] Unable to get l3_device for 'mob1s1a1'
[Thu Oct 17 06:23:26] IPV4 ping failed for 'mob1s1a1'
[Thu Oct 17 06:23:26] Checking network.interface.'mob1s1a1' ubus ID
[Thu Oct 17 06:23:26] Interface 'mob1s1a1' going down
[Thu Oct 17 06:23:26] Service reload initiated
[Thu Oct 17 06:23:26] [gsm.modem0] Config reload initiated
[Thu Oct 17 06:23:26] Checking network.interface.'mob1s1a1' ubus ID
[Thu Oct 17 06:23:26] Interface 'mob1s1a1' going down
[Thu Oct 17 06:23:26] [gsm.modem0] Cancelling uloop timeouts
[Thu Oct 17 06:23:26] [gsm.modem1] Config reload initiated
[Thu Oct 17 06:23:26] Checking network.interface.'mob2s1a1' ubus ID
[Thu Oct 17 06:23:26] Interface 'mob2s1a1' going down