Weird LTE Connection Downtime and DHCP Issues - RUTX11

Teltonika Networks
1

Hello All,

I’m experiencing a weird LTE connection issue with my RUTX11 router. I’m using a SIM card from an LTE-based home broadband connection, and my connection goes down for 1-2 minutes every day, once or twice.

This SIM card originally worked without any issues in a Huawei LTE CPE, but after I moved it to the RUTX11 router, this problem started occurring.

When I check the logs, I noticed that this issue happens exactly during the DHCP lease renewal process. The udhcpc attempts to renew the lease, and the log line for DHCP renewal is present. However, after a few seconds, it reports ‘mobile data disconnected.’

Could this be related to a busybox udhcpc bug? How can I resolve this issue?

I have attached the logs for reference.

Nov 20 02:11:29 10.80.80.2 netifd: mob1s1a1_4 (5860): udhcpc: started, v1.34.1
Nov 20 02:11:29 10.80.80.2 netifd: mob1s1a1_4 (5860): udhcpc: broadcasting discover
Nov 20 02:11:29 10.80.80.2 netifd: mob1s1a1_4 (5860): udhcpc: broadcasting select for 100.106.20.229, server 100.106.20.230
Nov 20 02:11:29 10.80.80.2 netifd: mob1s1a1_4 (5860): udhcpc: lease of 100.106.20.229 obtained from 100.106.20.230, lease time 7200
Nov 20 03:11:29 10.80.80.2 netifd: mob1s1a1_4 (5860): udhcpc: broadcasting renew
Nov 20 03:11:29 10.80.80.2 netifd: mob1s1a1_4 (5860): udhcpc: lease of 100.106.20.229 obtained from 100.106.20.230, lease time 7200
Nov 20 04:11:28 10.80.80.2 netifd: mob1s1a1_4 (5860): udhcpc: broadcasting renew
Nov 20 04:11:29 10.80.80.2 netifd: mob1s1a1_4 (5860): udhcpc: lease of 100.106.20.229 obtained from 100.106.20.230, lease time 7200
Nov 20 05:11:29 10.80.80.2 netifd: mob1s1a1_4 (5860): udhcpc: broadcasting renew
Nov 20 05:11:29 10.80.80.2 netifd: mob1s1a1_4 (5860): udhcpc: lease of 100.106.20.229 obtained from 100.106.20.230, lease time 7200
Nov 20 06:11:29 10.80.80.2 netifd: mob1s1a1_4 (5860): udhcpc: broadcasting renew
Nov 20 06:11:29 10.80.80.2 netifd: mob1s1a1_4 (5860): udhcpc: lease of 100.106.20.229 obtained from 100.106.20.230, lease time 7200
Nov 20 07:11:29 10.80.80.2 netifd: mob1s1a1_4 (5860): udhcpc: broadcasting renew
Nov 20 07:11:29 10.80.80.2 netifd: mob1s1a1_4 (5860): udhcpc: lease of 100.106.20.229 obtained from 100.106.20.230, lease time 7200
Nov 20 08:11:28 10.80.80.2 netifd: mob1s1a1_4 (5860): udhcpc: broadcasting renew
Nov 20 08:11:28 10.80.80.2 netifd: mob1s1a1_4 (5860): udhcpc: lease of 100.106.20.229 obtained from 100.106.20.230, lease time 7200
Nov 20 09:11:28 10.80.80.2 netifd: mob1s1a1_4 (5860): udhcpc: broadcasting renew
Nov 20 09:11:29 10.80.80.2 netifd: mob1s1a1_4 (5860): udhcpc: lease of 100.106.20.229 obtained from 100.106.20.230, lease time 7200
Nov 20 11:04:57 10.80.80.2 netifd: mob1s1a1_4 (6042): udhcpc: broadcasting renew
Nov 20 11:04:57 10.80.80.2 netifd: mob1s1a1_4 (6042): udhcpc: lease of 100.104.60.68 obtained from 100.104.60.69, lease time 7200
Nov 20 12:04:57 10.80.80.2 netifd: mob1s1a1_4 (6042): udhcpc: broadcasting renew
Nov 20 12:04:57 10.80.80.2 netifd: mob1s1a1_4 (6042): udhcpc: lease of 100.104.60.68 obtained from 100.104.60.69, lease time 7200
Nov 20 13:04:57 10.80.80.2 netifd: mob1s1a1_4 (6042): udhcpc: broadcasting renew
Nov 20 13:04:57 10.80.80.2 netifd: mob1s1a1_4 (6042): udhcpc: lease of 100.104.60.68 obtained from 100.104.60.69, lease time 7200
Nov 20 14:04:58 10.80.80.2 netifd: mob1s1a1_4 (6042): udhcpc: broadcasting renew
Nov 20 14:04:58 10.80.80.2 netifd: mob1s1a1_4 (6042): udhcpc: lease of 100.104.60.68 obtained from 100.104.60.69, lease time 7200
Nov 20 15:04:58 10.80.80.2 netifd: mob1s1a1_4 (6042): udhcpc: broadcasting renew
Nov 20 15:04:58 10.80.80.2 netifd: mob1s1a1_4 (6042): udhcpc: lease of 100.104.60.68 obtained from 100.104.60.69, lease time 7200
Nov 20 16:04:58 10.80.80.2 netifd: mob1s1a1_4 (6042): udhcpc: broadcasting renew
Nov 20 16:04:58 10.80.80.2 netifd: mob1s1a1_4 (6042): udhcpc: lease of 100.104.60.68 obtained from 100.104.60.69, lease time 7200
Nov 20 17:04:58 10.80.80.2 netifd: mob1s1a1_4 (6042): udhcpc: broadcasting renew
Nov 20 17:04:58 10.80.80.2 netifd: mob1s1a1_4 (6042): udhcpc: lease of 100.104.60.68 obtained from 100.104.60.69, lease time 7200
Nov 20 18:04:58 10.80.80.2 netifd: mob1s1a1_4 (6042): udhcpc: broadcasting renew
Nov 20 18:04:58 10.80.80.2 netifd: mob1s1a1_4 (6042): udhcpc: lease of 100.104.60.68 obtained from 100.104.60.69, lease time 7200
Nov 20 19:04:58 10.80.80.2 netifd: mob1s1a1_4 (6042): udhcpc: broadcasting renew
Nov 20 19:04:59 10.80.80.2 netifd: mob1s1a1_4 (6042): udhcpc: lease of 100.104.60.68 obtained from 100.104.60.69, lease time 7200
Nov 20 20:06:51 10.80.80.2 netifd: mob1s1a1_4 (12450): udhcpc: broadcasting select for 100.104.63.209, server 100.104.63.210
Nov 20 20:06:51 10.80.80.2 netifd: mob1s1a1_4 (12450): udhcpc: lease of 100.104.63.209 obtained from 100.104.63.210, lease time 7200
3

Hello,

Thank you for providing the logs!
I’ll ask you to update the device to the latest firmware, wait for the issue to replicate, then via CLI/SSH run the following command:

cat /tmp/mobile/mobifd.log

And paste the information from the beginning of the disconnection. This log file is responsible for the mobile connection itself, and should contain more verbose logs.
Make sure to remove any sensitive information before posting them here!

Best regards,

4

Hi @Daumantas,

Thanks for the fast response. Pasting logs here. Please note that I tried to change few setting on the router. these also may be reflected in the log file. Please let me know if these logs are enough.

[Tue Nov 21 09:27:29] [gsm.modem0] Config reload initiated
[Tue Nov 21 09:27:29] Checking network.interface.'mob1s1a1' ubus ID
[Tue Nov 21 09:27:29] Interface 'mob1s1a1' going down
[Tue Nov 21 09:27:29] [gsm.modem0] Cancelling uloop timeouts
[Tue Nov 21 09:27:29] [gsm.modem0] Active SIM: "1"
[Tue Nov 21 09:27:29] Option 'pdptype' changed, removing 'pref_apn' for 'mob1s1a1' interface
[Tue Nov 21 09:27:29] [gsm.modem0] Modem is already in CFUN: "2"(full) state
[Tue Nov 21 09:27:29] [gsm.modem0] SIM is ready
[Tue Nov 21 09:27:29] [gsm.modem0] Init: functionality
[Tue Nov 21 09:27:29] [gsm.modem0] Modem is already in CFUN: "2"(full) state
[Tue Nov 21 09:27:29] [gsm.modem0] Init: dhcp filter
[Tue Nov 21 09:27:29] [gsm.modem0] Init: ue usage
[Tue Nov 21 09:27:29] [gsm.modem0] GSM error in "generic_ue_usage_cb:73" (Called out method not supported), gsmd_method_id: "83"
[Tue Nov 21 09:27:29] [gsm.modem0] Init: mbn_autosel
[Tue Nov 21 09:27:29] [gsm.modem0] Init: auto_timezone
[Tue Nov 21 09:27:29] [gsm.modem0] Init: mbn
[Tue Nov 21 09:27:29] [gsm.modem0] MBN: "ROW_Generic_3GPP", SELECTED: "1", ACTIVE: "1"
[Tue Nov 21 09:27:29] [gsm.modem0] Init: usbnet
[Tue Nov 21 09:27:29] [gsm.modem0] Init: nat settings
[Tue Nov 21 09:27:29] [gsm.modem0] GSM error in "generic_nat_set_cb:309" (Called out method not supported), gsmd_method_id: "81"
[Tue Nov 21 09:27:29] [gsm.modem0] Init: auth bit
[Tue Nov 21 09:27:29] [gsm.modem0] GSM error in "generic_set_auth_bit:337" (Called out method not supported), gsmd_method_id: "149"
[Tue Nov 21 09:27:29] [gsm.modem0] Init: mgcfg
[Tue Nov 21 09:27:29] [gsm.modem0] GSM error in "generic_mgcfg_cb:366" (Called out method not supported), gsmd_method_id: "103"
[Tue Nov 21 09:27:29] [gsm.modem0] Init: msg fmt
[Tue Nov 21 09:27:29] [gsm.modem0] Init: urc port
[Tue Nov 21 09:27:29] [gsm.modem0] Init: signal query
[Tue Nov 21 09:27:29] [gsm.modem0] Init: sim state
[Tue Nov 21 09:27:29] [gsm.modem0] GSM error in "generic_sim_state_cfg_cb:483" (Called out method not supported)
[Tue Nov 21 09:27:29] [gsm.modem0] Init: sms mode
[Tue Nov 21 09:27:29] [gsm.modem0] Init: roaming svc
[Tue Nov 21 09:27:29] [gsm.modem0] Init: m2m_func
[Tue Nov 21 09:27:29] [gsm.modem0] GSM error in "generic_set_m2m_state:688" (Called out method not supported), gsmd_method_id: "96"
[Tue Nov 21 09:27:29] [gsm.modem0] Init: urc_cause
[Tue Nov 21 09:27:29] [gsm.modem0] GSM error in "generic_enable_urc_cause:532" (Called out method not supported), gsmd_method_id: "161"
[Tue Nov 21 09:27:29] [gsm.modem0] Init: modem_func
[Tue Nov 21 09:27:29] [gsm.modem0] Modem is in dynamic operation mode
[Tue Nov 21 09:27:29] [gsm.modem0] Init: sms_storage_check
[Tue Nov 21 09:27:29] [gsm.modem0] Init: set_urc_ind_cfg
[Tue Nov 21 09:27:29] [gsm.modem0] Modem is already in CFUN: "2"(full) state
[Tue Nov 21 09:27:29] [gsm.modem0] Init: functionality
[Tue Nov 21 09:27:29] [gsm.modem0] Modem is already in CFUN: "2"(full) state
[Tue Nov 21 09:27:29] [gsm.modem0] Init: net reg
[Tue Nov 21 09:27:29] [gsm.modem0] Init: net greg
[Tue Nov 21 09:27:29] [gsm.modem0] Init: net ereg
[Tue Nov 21 09:27:29] [gsm.modem0] Init: net c5greg
[Tue Nov 21 09:27:29] [gsm.modem0] GSM error in "mb_set_net_reg:603" (Called out method not supported), gsmd_method_id: "24"
[Tue Nov 21 09:27:29] [gsm.modem0] Init: clip mode
[Tue Nov 21 09:27:29] [gsm.modem0] Init: CRC mode
[Tue Nov 21 09:27:29] [gsm.modem0] Init: CNMI mode
[Tue Nov 21 09:27:29] [gsm.modem0] Scheduling init process 'CNMI mode' for "200ms"
[Tue Nov 21 09:27:29] [gsm.modem0] Modem is already in CFUN: "2"(full) state
[Tue Nov 21 09:27:29] [gsm.modem0] Init process was sheduled, last init entry executed 'CNMI mode'
[Tue Nov 21 09:27:29] [gsm.modem0] Init: net mode
[Tue Nov 21 09:27:29] [gsm.modem0] Will use "auto" service mode.
[Tue Nov 21 09:27:29] [gsm.modem0] Modem is in correct service mode "Auto"
[Tue Nov 21 09:27:29] [gsm.modem0] GSM error in "set_network_category:258" (Called out method not supported), gsmd_method_id: "114"
[Tue Nov 21 09:27:29] [gsm.modem0] Network category "m1_and_nb1" is not supported. Network category won't be changed
[Tue Nov 21 09:27:29] [gsm.modem0] Scheduling init process 'net mode' for "1500ms"
[Tue Nov 21 09:27:31] [gsm.modem0] Modem is already in CFUN: "2"(full) state
[Tue Nov 21 09:27:31] [gsm.modem0] Init process was sheduled, last init entry executed 'net mode'
[Tue Nov 21 09:27:31] [gsm.modem0] Init: default bands
[Tue Nov 21 09:27:31] [gsm.modem0] Init: SMS storage
[Tue Nov 21 09:27:31] [gsm.modem0] SMS storage: "sm"
[Tue Nov 21 09:27:31] [gsm.modem0] Modem settings init completed
[Tue Nov 21 09:27:31] [gsm.modem0] Starting connection setup
[Tue Nov 21 09:27:31] Touch: /tmp/vuci/reload_routes
[Tue Nov 21 09:27:31] [gsm.modem0] Getting APN list
[Tue Nov 21 09:27:31] [gsm.modem0] Trying to select APN with "ipv4v6" pdptype
[Tue Nov 21 09:27:31] [gsm.modem0] Will use "" APN with "ipv4v6" pdptype
[Tue Nov 21 09:27:31] Registering event handler 'mob1s1a1'
[Tue Nov 21 09:27:31] [gsm.modem0] APN "IMS" is already set for PDP 2
[Tue Nov 21 09:27:31] [gsm.modem0] Modem is already in CFUN: "2"(full) state
[Tue Nov 21 09:27:31] [gsm.modem0] Preparing connection for 'mob1s1a1' interface
[Tue Nov 21 09:27:31] [gsm.modem0] Skipping first APN to not overwrite APN for 'mob1s1a1' interface
[Tue Nov 21 09:27:31] [gsm.modem0] APN "" isn't prepared for PDP "1", preparing it
[Tue Nov 21 09:27:31] [gsm.modem0] Setting CFUN: "3"(rf)
[Tue Nov 21 09:27:32] [gsm.modem0] Setting CFUN: "2"(full)
[Tue Nov 21 09:27:35] [gsm.modem0] APN "" was successfully prepared for PDP "1"
[Tue Nov 21 09:27:35] [gsm.modem0] Attempting to establish connection to operator with "15s" of timeout
[Tue Nov 21 09:27:35] [gsm.modem0] Network registration status received: "2"(Searching)
[Tue Nov 21 09:27:35] [gsm.modem0] Mobile state: "Setting up connection settings"
[Tue Nov 21 09:27:35] [gsm.modem0] Network registration status received: "0"(Not registered)
[Tue Nov 21 09:27:35] [gsm.modem0] Mobile state: "Setting up connection settings"
[Tue Nov 21 09:27:35] [gsm.modem0] timeout: "15" get_timeout: "15"
[Tue Nov 21 09:27:35] [gsm.modem0] Modem is already in COPS: "1"(Auto) state
[Tue Nov 21 09:27:35] [gsm.modem0] Testing connection to "" operator
[Tue Nov 21 09:27:35] [gsm.modem0] Connected to operator "413 12 Sri Lanka SLT"
[Tue Nov 21 09:27:35] [gsm.modem0] Will use "auto" service mode.
[Tue Nov 21 09:27:35] [gsm.modem0] Modem is in correct service mode "Auto"
[Tue Nov 21 09:27:35] [gsm.modem0] GSM error in "set_network_category:258" (Called out method not supported), gsmd_method_id: "114"
[Tue Nov 21 09:27:35] [gsm.modem0] Network category "m1_and_nb1" is not supported. Network category won't be changed
[Tue Nov 21 09:27:35] [gsm.modem0] MBN: "ROW_Generic_3GPP", SELECTED: "1", ACTIVE: "1"
[Tue Nov 21 09:27:35] [gsm.modem0] MBN: "ROW_Generic_3GPP" saved to 'simcard' config
[Tue Nov 21 09:27:35] [gsm.modem0] Network registration status received: "0"(Not registered)
[Tue Nov 21 09:27:35] [gsm.modem0] Mobile state: "Mobile connection setup is complete"
[Tue Nov 21 09:27:35] [gsm.modem0] Network registration status received: "2"(Searching)
[Tue Nov 21 09:27:35] [gsm.modem0] Mobile state: "Mobile connection setup is complete"
[Tue Nov 21 09:27:35] [gsm.modem0] Network registration status received: "3"(Denied)
[Tue Nov 21 09:27:35] [gsm.modem0] Mobile state: "Mobile connection setup is complete"
[Tue Nov 21 09:27:35] Touch: /tmp/vuci/denied_event_3-1
[Tue Nov 21 09:27:35] [gsm.modem0] Network registration status received: "1"(Registered, home)
[Tue Nov 21 09:27:35] [gsm.modem0] Mobile state: "Mobile connection setup is complete"
[Tue Nov 21 09:27:35] Touch: /tmp/vuci/reload_routes
[Tue Nov 21 09:27:35] [gsm.modem0] -CFUN- Functionality: "Full"
[Tue Nov 21 09:27:35] [gsm.modem0] -COPS- Mode: "Auto", operator: "413 12 Sri Lanka SLT"
[Tue Nov 21 09:27:35] [gsm.modem0] -CREG- Mode: "Enabled (with location information)", status: "Denied", LAC: "", cell ID: "", technology: "Unknown"
[Tue Nov 21 09:27:35] [gsm.modem0] -CGREG- Mode: "Enabled (with location information)", status: "Registered, home", LAC: "201", cell ID: "5223681", technology: "E-UTRAN"
[Tue Nov 21 09:27:35] [gsm.modem0] -CEREG- Mode: "Enabled (with location information)", status: "Registered, home", LAC: "201", cell ID: "5223681", technology: "E-UTRAN"
[Tue Nov 21 09:27:35] [gsm.modem0] GSM error in "mb_print_reg_status:723" (Called out method not supported), gsmd_method_id: "24"
[Tue Nov 21 09:27:36] [gsm.modem0] IP address on PDP context "1": "100.104.112.182"
[Tue Nov 21 09:27:36] [gsm.modem0] Checking IP address on PDP context "1"
[Tue Nov 21 09:27:36] [gsm.modem0] PDP type on PDP context "1" is correct
[Tue Nov 21 09:27:36] Saving APN information in "network" config for 'mob1s1a1' interface
[Tue Nov 21 09:27:36] Reloading network
[Tue Nov 21 09:27:36] Checking network.interface.'mob1s1a1' ubus ID
[Tue Nov 21 09:27:36] Interface 'mob1s1a1' is going up
[Tue Nov 21 09:27:36] Setting IFUP timeout for 'mob1s1a1' of "120s"
[Tue Nov 21 09:27:43] Network event received "ifup" for 'mob1s1a1_4'
[Tue Nov 21 09:27:43] Sending PING "qmimux0" -> "8.8.8.8"
[Tue Nov 21 09:27:44] PING to "8.8.8.8" passed
[Tue Nov 21 09:27:44] [gsm.modem0] VoLTE: "Inactive"
[Tue Nov 21 09:27:44] Connection setup completed for 'mob1s1a1'
[Tue Nov 21 10:45:40] Service reload initiated
[Tue Nov 21 10:45:40] [gsm.modem0] Config reload initiated
[Tue Nov 21 10:45:40] Checking network.interface.'mob1s1a1' ubus ID
[Tue Nov 21 10:45:40] Interface 'mob1s1a1' going down
[Tue Nov 21 10:45:40] [gsm.modem0] Cancelling uloop timeouts
[Tue Nov 21 10:45:40] [gsm.modem0] Active SIM: "1"
[Tue Nov 21 10:45:40] [gsm.modem0] Modem is already in CFUN: "2"(full) state
[Tue Nov 21 10:45:40] [gsm.modem0] SIM is ready
[Tue Nov 21 10:45:40] [gsm.modem0] Init: functionality
[Tue Nov 21 10:45:40] [gsm.modem0] Modem is already in CFUN: "2"(full) state
[Tue Nov 21 10:45:40] [gsm.modem0] Init: dhcp filter
[Tue Nov 21 10:45:40] [gsm.modem0] Init: ue usage
[Tue Nov 21 10:45:40] [gsm.modem0] GSM error in "generic_ue_usage_cb:73" (Called out method not supported), gsmd_method_id: "83"
[Tue Nov 21 10:45:40] [gsm.modem0] Init: mbn_autosel
[Tue Nov 21 10:45:40] [gsm.modem0] Init: auto_timezone
[Tue Nov 21 10:45:40] [gsm.modem0] Init: mbn
[Tue Nov 21 10:45:40] [gsm.modem0] MBN: "ROW_Generic_3GPP", SELECTED: "1", ACTIVE: "1"
[Tue Nov 21 10:45:40] [gsm.modem0] Init: usbnet
[Tue Nov 21 10:45:40] [gsm.modem0] Init: nat settings
[Tue Nov 21 10:45:40] [gsm.modem0] GSM error in "generic_nat_set_cb:309" (Called out method not supported), gsmd_method_id: "81"
[Tue Nov 21 10:45:40] [gsm.modem0] Init: auth bit
[Tue Nov 21 10:45:40] [gsm.modem0] GSM error in "generic_set_auth_bit:337" (Called out method not supported), gsmd_method_id: "149"
[Tue Nov 21 10:45:40] [gsm.modem0] Init: mgcfg
[Tue Nov 21 10:45:40] [gsm.modem0] GSM error in "generic_mgcfg_cb:366" (Called out method not supported), gsmd_method_id: "103"
[Tue Nov 21 10:45:40] [gsm.modem0] Init: msg fmt
[Tue Nov 21 10:45:40] [gsm.modem0] Init: urc port
[Tue Nov 21 10:45:40] [gsm.modem0] Init: signal query
[Tue Nov 21 10:45:40] [gsm.modem0] Init: sim state
[Tue Nov 21 10:45:40] [gsm.modem0] GSM error in "generic_sim_state_cfg_cb:483" (Called out method not supported)
[Tue Nov 21 10:45:40] [gsm.modem0] Init: sms mode
[Tue Nov 21 10:45:40] [gsm.modem0] Init: roaming svc
[Tue Nov 21 10:45:40] [gsm.modem0] Init: m2m_func
[Tue Nov 21 10:45:40] [gsm.modem0] GSM error in "generic_set_m2m_state:688" (Called out method not supported), gsmd_method_id: "96"
[Tue Nov 21 10:45:40] [gsm.modem0] Init: urc_cause
[Tue Nov 21 10:45:40] [gsm.modem0] GSM error in "generic_enable_urc_cause:532" (Called out method not supported), gsmd_method_id: "161"
[Tue Nov 21 10:45:40] [gsm.modem0] Init: modem_func
[Tue Nov 21 10:45:40] [gsm.modem0] Modem is in dynamic operation mode
[Tue Nov 21 10:45:40] [gsm.modem0] Init: sms_storage_check
[Tue Nov 21 10:45:40] [gsm.modem0] Init: set_urc_ind_cfg
[Tue Nov 21 10:45:40] [gsm.modem0] Modem is already in CFUN: "2"(full) state
[Tue Nov 21 10:45:40] [gsm.modem0] Init: functionality
[Tue Nov 21 10:45:40] [gsm.modem0] Modem is already in CFUN: "2"(full) state
[Tue Nov 21 10:45:40] [gsm.modem0] Init: net reg
[Tue Nov 21 10:45:40] [gsm.modem0] Init: net greg
[Tue Nov 21 10:45:40] [gsm.modem0] Init: net ereg
[Tue Nov 21 10:45:40] [gsm.modem0] Init: net c5greg
[Tue Nov 21 10:45:40] [gsm.modem0] GSM error in "mb_set_net_reg:603" (Called out method not supported), gsmd_method_id: "24"
[Tue Nov 21 10:45:40] [gsm.modem0] Init: clip mode
[Tue Nov 21 10:45:40] [gsm.modem0] Init: CRC mode
[Tue Nov 21 10:45:40] [gsm.modem0] Init: CNMI mode
[Tue Nov 21 10:45:40] [gsm.modem0] Scheduling init process 'CNMI mode' for "200ms"
[Tue Nov 21 10:45:41] [gsm.modem0] Modem is already in CFUN: "2"(full) state
[Tue Nov 21 10:45:41] [gsm.modem0] Init process was sheduled, last init entry executed 'CNMI mode'
[Tue Nov 21 10:45:41] [gsm.modem0] Init: net mode
[Tue Nov 21 10:45:41] [gsm.modem0] Will use "auto" service mode.
[Tue Nov 21 10:45:41] [gsm.modem0] Modem is in correct service mode "Auto"
[Tue Nov 21 10:45:41] [gsm.modem0] GSM error in "set_network_category:258" (Called out method not supported), gsmd_method_id: "114"
[Tue Nov 21 10:45:41] [gsm.modem0] Network category "m1_and_nb1" is not supported. Network category won't be changed
[Tue Nov 21 10:45:41] [gsm.modem0] Scheduling init process 'net mode' for "1500ms"
[Tue Nov 21 10:45:42] [gsm.modem0] Modem is already in CFUN: "2"(full) state
[Tue Nov 21 10:45:42] [gsm.modem0] Init process was sheduled, last init entry executed 'net mode'
[Tue Nov 21 10:45:42] [gsm.modem0] Init: default bands
[Tue Nov 21 10:45:42] [gsm.modem0] Init: SMS storage
[Tue Nov 21 10:45:42] [gsm.modem0] SMS storage: "sm"
[Tue Nov 21 10:45:42] [gsm.modem0] Modem settings init completed
[Tue Nov 21 10:45:42] [gsm.modem0] Starting connection setup
[Tue Nov 21 10:45:42] Touch: /tmp/vuci/reload_routes
[Tue Nov 21 10:45:42] [gsm.modem0] Getting APN list
[Tue Nov 21 10:45:42] [gsm.modem0] Trying to select APN with "ipv4v6" pdptype
[Tue Nov 21 10:45:42] [gsm.modem0] Will use "" APN with "ipv4v6" pdptype
[Tue Nov 21 10:45:42] Registering event handler 'mob1s1a1'
[Tue Nov 21 10:45:42] [gsm.modem0] APN "IMS" is already set for PDP 2
[Tue Nov 21 10:45:42] [gsm.modem0] Modem is already in CFUN: "2"(full) state
[Tue Nov 21 10:45:42] [gsm.modem0] Preparing connection for 'mob1s1a1' interface
[Tue Nov 21 10:45:42] [gsm.modem0] Skipping first APN to not overwrite APN for 'mob1s1a1' interface
[Tue Nov 21 10:45:42] [gsm.modem0] APN "" is already set for PDP 1
[Tue Nov 21 10:45:42] [gsm.modem0] Attempting to establish connection to operator with "15s" of timeout
[Tue Nov 21 10:45:42] [gsm.modem0] timeout: "15" get_timeout: "15"
[Tue Nov 21 10:45:42] [gsm.modem0] Modem is already in COPS: "1"(Auto) state
[Tue Nov 21 10:45:42] [gsm.modem0] Testing connection to "" operator
[Tue Nov 21 10:45:42] [gsm.modem0] Connected to operator "413 12 Sri Lanka SLT"
[Tue Nov 21 10:45:42] [gsm.modem0] Will use "auto" service mode.
[Tue Nov 21 10:45:42] [gsm.modem0] Modem is in correct service mode "Auto"
[Tue Nov 21 10:45:42] [gsm.modem0] GSM error in "set_network_category:258" (Called out method not supported), gsmd_method_id: "114"
[Tue Nov 21 10:45:42] [gsm.modem0] Network category "m1_and_nb1" is not supported. Network category won't be changed
[Tue Nov 21 10:45:42] [gsm.modem0] MBN: "ROW_Generic_3GPP", SELECTED: "1", ACTIVE: "1"
[Tue Nov 21 10:45:42] [gsm.modem0] MBN: "ROW_Generic_3GPP" saved to 'simcard' config
[Tue Nov 21 10:45:42] [gsm.modem0] -CFUN- Functionality: "Full"
[Tue Nov 21 10:45:42] [gsm.modem0] -COPS- Mode: "Auto", operator: "413 12 Sri Lanka SLT"
[Tue Nov 21 10:45:42] [gsm.modem0] -CREG- Mode: "Enabled (with location information)", status: "Denied", LAC: "", cell ID: "", technology: "Unknown"
[Tue Nov 21 10:45:42] [gsm.modem0] -CGREG- Mode: "Enabled (with location information)", status: "Registered, home", LAC: "201", cell ID: "5223681", technology: "E-UTRAN"
[Tue Nov 21 10:45:42] [gsm.modem0] -CEREG- Mode: "Enabled (with location information)", status: "Registered, home", LAC: "201", cell ID: "5223681", technology: "E-UTRAN"
[Tue Nov 21 10:45:42] [gsm.modem0] GSM error in "mb_print_reg_status:723" (Called out method not supported), gsmd_method_id: "24"
[Tue Nov 21 10:45:43] [gsm.modem0] IP address on PDP context "1": "100.104.112.182"
[Tue Nov 21 10:45:43] [gsm.modem0] Checking IP address on PDP context "1"
[Tue Nov 21 10:45:43] [gsm.modem0] PDP type on PDP context "1" is correct
[Tue Nov 21 10:45:43] Saving APN information in "network" config for 'mob1s1a1' interface
[Tue Nov 21 10:45:43] Reloading network
[Tue Nov 21 10:45:43] Checking network.interface.'mob1s1a1' ubus ID
[Tue Nov 21 10:45:43] Interface 'mob1s1a1' is going up
[Tue Nov 21 10:45:43] Setting IFUP timeout for 'mob1s1a1' of "120s"
[Tue Nov 21 10:45:47] Network event received "ifup" for 'mob1s1a1_4'
[Tue Nov 21 10:45:47] Sending PING "qmimux0" -> "8.8.8.8"
[Tue Nov 21 10:45:48] PING to "8.8.8.8" passed
[Tue Nov 21 10:45:48] [gsm.modem0] VoLTE: "Inactive"
[Tue Nov 21 10:45:48] Connection setup completed for 'mob1s1a1'
[Tue Nov 21 10:50:20] [gsm.modem0] Config reload initiated
[Tue Nov 21 10:50:20] Checking network.interface.'mob1s1a1' ubus ID
[Tue Nov 21 10:50:20] Interface 'mob1s1a1' going down
[Tue Nov 21 10:50:20] [gsm.modem0] Cancelling uloop timeouts
[Tue Nov 21 10:50:20] [gsm.modem0] Active SIM: "1"
[Tue Nov 21 10:50:20] [gsm.modem0] Modem is already in CFUN: "2"(full) state
[Tue Nov 21 10:50:20] [gsm.modem0] SIM is ready
[Tue Nov 21 10:50:20] [gsm.modem0] Init: functionality
[Tue Nov 21 10:50:20] [gsm.modem0] Modem is already in CFUN: "2"(full) state
[Tue Nov 21 10:50:20] [gsm.modem0] Init: dhcp filter
[Tue Nov 21 10:50:20] [gsm.modem0] Init: ue usage
[Tue Nov 21 10:50:20] [gsm.modem0] GSM error in "generic_ue_usage_cb:73" (Called out method not supported), gsmd_method_id: "83"
[Tue Nov 21 10:50:20] [gsm.modem0] Init: mbn_autosel
[Tue Nov 21 10:50:20] [gsm.modem0] Init: auto_timezone
[Tue Nov 21 10:50:20] [gsm.modem0] Init: mbn
[Tue Nov 21 10:50:20] [gsm.modem0] MBN: "ROW_Generic_3GPP", SELECTED: "1", ACTIVE: "1"
[Tue Nov 21 10:50:20] [gsm.modem0] Init: usbnet
[Tue Nov 21 10:50:20] [gsm.modem0] Init: nat settings
[Tue Nov 21 10:50:20] [gsm.modem0] GSM error in "generic_nat_set_cb:309" (Called out method not supported), gsmd_method_id: "81"
[Tue Nov 21 10:50:20] [gsm.modem0] Init: auth bit
[Tue Nov 21 10:50:20] [gsm.modem0] GSM error in "generic_set_auth_bit:337" (Called out method not supported), gsmd_method_id: "149"
[Tue Nov 21 10:50:20] [gsm.modem0] Init: mgcfg
[Tue Nov 21 10:50:20] [gsm.modem0] GSM error in "generic_mgcfg_cb:366" (Called out method not supported), gsmd_method_id: "103"
[Tue Nov 21 10:50:20] [gsm.modem0] Init: msg fmt
[Tue Nov 21 10:50:20] [gsm.modem0] Init: urc port
[Tue Nov 21 10:50:20] [gsm.modem0] Init: signal query
[Tue Nov 21 10:50:20] [gsm.modem0] Init: sim state
[Tue Nov 21 10:50:20] [gsm.modem0] GSM error in "generic_sim_state_cfg_cb:483" (Called out method not supported)
[Tue Nov 21 10:50:20] [gsm.modem0] Init: sms mode
[Tue Nov 21 10:50:20] [gsm.modem0] Init: roaming svc
[Tue Nov 21 10:50:20] [gsm.modem0] Init: m2m_func
[Tue Nov 21 10:50:20] [gsm.modem0] GSM error in "generic_set_m2m_state:688" (Called out method not supported), gsmd_method_id: "96"
[Tue Nov 21 10:50:20] [gsm.modem0] Init: urc_cause
[Tue Nov 21 10:50:20] [gsm.modem0] GSM error in "generic_enable_urc_cause:532" (Called out method not supported), gsmd_method_id: "161"
[Tue Nov 21 10:50:20] [gsm.modem0] Init: modem_func
[Tue Nov 21 10:50:20] [gsm.modem0] Modem is in dynamic operation mode
[Tue Nov 21 10:50:20] [gsm.modem0] Init: sms_storage_check
[Tue Nov 21 10:50:20] [gsm.modem0] Init: set_urc_ind_cfg
[Tue Nov 21 10:50:20] [gsm.modem0] Modem is already in CFUN: "2"(full) state
[Tue Nov 21 10:50:20] [gsm.modem0] Init: functionality
[Tue Nov 21 10:50:20] [gsm.modem0] Modem is already in CFUN: "2"(full) state
[Tue Nov 21 10:50:20] [gsm.modem0] Init: net reg
[Tue Nov 21 10:50:20] [gsm.modem0] Init: net greg
[Tue Nov 21 10:50:20] [gsm.modem0] Init: net ereg
[Tue Nov 21 10:50:20] [gsm.modem0] Init: net c5greg
[Tue Nov 21 10:50:20] [gsm.modem0] GSM error in "mb_set_net_reg:603" (Called out method not supported), gsmd_method_id: "24"
[Tue Nov 21 10:50:20] [gsm.modem0] Init: clip mode
[Tue Nov 21 10:50:20] [gsm.modem0] Init: CRC mode
[Tue Nov 21 10:50:20] [gsm.modem0] Init: CNMI mode
[Tue Nov 21 10:50:20] [gsm.modem0] Scheduling init process 'CNMI mode' for "200ms"
[Tue Nov 21 10:50:20] [gsm.modem0] Modem is already in CFUN: "2"(full) state
[Tue Nov 21 10:50:20] [gsm.modem0] Init process was sheduled, last init entry executed 'CNMI mode'
[Tue Nov 21 10:50:20] [gsm.modem0] Init: net mode
[Tue Nov 21 10:50:20] [gsm.modem0] Will use "auto" service mode.
[Tue Nov 21 10:50:20] [gsm.modem0] Modem is in correct service mode "Auto"
[Tue Nov 21 10:50:20] [gsm.modem0] GSM error in "set_network_category:258" (Called out method not supported), gsmd_method_id: "114"
[Tue Nov 21 10:50:20] [gsm.modem0] Network category "m1_and_nb1" is not supported. Network category won't be changed
[Tue Nov 21 10:50:20] [gsm.modem0] Scheduling init process 'net mode' for "1500ms"
[Tue Nov 21 10:50:22] [gsm.modem0] Modem is already in CFUN: "2"(full) state
[Tue Nov 21 10:50:22] [gsm.modem0] Init process was sheduled, last init entry executed 'net mode'
[Tue Nov 21 10:50:22] [gsm.modem0] Init: default bands
[Tue Nov 21 10:50:22] [gsm.modem0] Init: SMS storage
[Tue Nov 21 10:50:22] [gsm.modem0] SMS storage: "sm"
[Tue Nov 21 10:50:22] [gsm.modem0] Modem settings init completed
[Tue Nov 21 10:50:22] [gsm.modem0] Starting connection setup
[Tue Nov 21 10:50:22] Touch: /tmp/vuci/reload_routes
[Tue Nov 21 10:50:22] [gsm.modem0] Getting APN list
[Tue Nov 21 10:50:22] [gsm.modem0] Trying to select APN with "ipv4v6" pdptype
[Tue Nov 21 10:50:22] [gsm.modem0] Will use "" APN with "ipv4v6" pdptype
[Tue Nov 21 10:50:22] Registering event handler 'mob1s1a1'
[Tue Nov 21 10:50:22] [gsm.modem0] APN "IMS" is already set for PDP 2
[Tue Nov 21 10:50:22] [gsm.modem0] Modem is already in CFUN: "2"(full) state
[Tue Nov 21 10:50:22] [gsm.modem0] Preparing connection for 'mob1s1a1' interface
[Tue Nov 21 10:50:22] [gsm.modem0] Skipping first APN to not overwrite APN for 'mob1s1a1' interface
[Tue Nov 21 10:50:22] [gsm.modem0] APN "" is already set for PDP 1
[Tue Nov 21 10:50:22] [gsm.modem0] Attempting to establish connection to operator with "15s" of timeout
[Tue Nov 21 10:50:22] [gsm.modem0] timeout: "15" get_timeout: "15"
[Tue Nov 21 10:50:22] [gsm.modem0] Modem is already in COPS: "1"(Auto) state
[Tue Nov 21 10:50:22] [gsm.modem0] Testing connection to "" operator
[Tue Nov 21 10:50:22] [gsm.modem0] Connected to operator "413 12 Sri Lanka SLT"
[Tue Nov 21 10:50:22] [gsm.modem0] Will use "auto" service mode.
[Tue Nov 21 10:50:22] [gsm.modem0] Modem is in correct service mode "Auto"
[Tue Nov 21 10:50:22] [gsm.modem0] GSM error in "set_network_category:258" (Called out method not supported), gsmd_method_id: "114"
[Tue Nov 21 10:50:22] [gsm.modem0] Network category "m1_and_nb1" is not supported. Network category won't be changed
[Tue Nov 21 10:50:22] [gsm.modem0] MBN: "ROW_Generic_3GPP", SELECTED: "1", ACTIVE: "1"
[Tue Nov 21 10:50:22] [gsm.modem0] MBN: "ROW_Generic_3GPP" saved to 'simcard' config
[Tue Nov 21 10:50:22] [gsm.modem0] -CFUN- Functionality: "Full"
[Tue Nov 21 10:50:22] [gsm.modem0] -COPS- Mode: "Auto", operator: "413 12 Sri Lanka SLT"
[Tue Nov 21 10:50:22] [gsm.modem0] -CREG- Mode: "Enabled (with location information)", status: "Denied", LAC: "", cell ID: "", technology: "Unknown"
[Tue Nov 21 10:50:22] [gsm.modem0] -CGREG- Mode: "Enabled (with location information)", status: "Registered, home", LAC: "201", cell ID: "5223681", technology: "E-UTRAN"
[Tue Nov 21 10:50:22] [gsm.modem0] -CEREG- Mode: "Enabled (with location information)", status: "Registered, home", LAC: "201", cell ID: "5223681", technology: "E-UTRAN"
[Tue Nov 21 10:50:22] [gsm.modem0] GSM error in "mb_print_reg_status:723" (Called out method not supported), gsmd_method_id: "24"
[Tue Nov 21 10:50:22] [gsm.modem0] IP address on PDP context "1": "100.104.112.182"
[Tue Nov 21 10:50:22] [gsm.modem0] Checking IP address on PDP context "1"
[Tue Nov 21 10:50:22] [gsm.modem0] PDP type on PDP context "1" is correct
[Tue Nov 21 10:50:22] Saving APN information in "network" config for 'mob1s1a1' interface
[Tue Nov 21 10:50:22] Reloading network
[Tue Nov 21 10:50:22] Checking network.interface.'mob1s1a1' ubus ID
[Tue Nov 21 10:50:22] Interface 'mob1s1a1' is going up
[Tue Nov 21 10:50:22] Setting IFUP timeout for 'mob1s1a1' of "120s"
[Tue Nov 21 10:50:31] Network event received "ifup" for 'mob1s1a1_4'
[Tue Nov 21 10:50:31] Sending PING "qmimux0" -> "8.8.8.8"
[Tue Nov 21 10:50:32] PING to "8.8.8.8" passed
[Tue Nov 21 10:50:32] [gsm.modem0] VoLTE: "Inactive"
[Tue Nov 21 10:50:32] Connection setup completed for 'mob1s1a1'

Thanks

Edit: Router and modem both are in latest firmware.

5

Hello,

Indeed, from the logs it’s seen that 3 changes in configuration were made (09:27:29, 10:45:40, and 10:50:20), thus the mobile connection was restarted. Could you wait for the issue with DHCP lease to replicate and take the logs then?
Nothing obvious is visible within the attached logs.

Best regards,

6

Hi @Daumantas

Attached new logs. Today, this happened multiple times and wan interface was keep flapping. My partner had to reboot the router to make it stable again and important logs may be lost during this reboot. Even I had configured remote syslog, modem logs are not forwarded

[Wed Nov 22 17:37:22] [gsm.modem0] Active SIM: "1"
[Wed Nov 22 17:37:22] [gsm.modem0] Created new modem instance
[Wed Nov 22 17:37:22] [gsm.modem0] Registering unsolicited listener
[Wed Nov 22 17:37:23] [gsm.modem0] Modem features were parsed successfully
[Wed Nov 22 17:37:23] [gsm.modem0] Starting modem settings init
[Wed Nov 22 17:37:23] [gsm.modem0] Setting CFUN: "2"(full)
[Wed Nov 22 17:37:27] [gsm.modem0] SIM is ready
[Wed Nov 22 17:37:27] [gsm.modem0] Init: functionality
[Wed Nov 22 17:37:27] [gsm.modem0] Modem is already in CFUN: "2"(full) state
[Wed Nov 22 17:37:27] [gsm.modem0] Init: dhcp filter
[Wed Nov 22 17:37:27] [gsm.modem0] Init: ue usage
[Wed Nov 22 17:37:27] [gsm.modem0] GSM error in "generic_ue_usage_cb:73" (Called out method not supported), gsmd_method_id: "83"
[Wed Nov 22 17:37:27] [gsm.modem0] Init: mbn_autosel
[Wed Nov 22 17:37:27] [gsm.modem0] Init: auto_timezone
[Wed Nov 22 17:37:27] [gsm.modem0] Init: mbn
[Wed Nov 22 17:37:27] [gsm.modem0] MBN: "ROW_Generic_3GPP", SELECTED: "1", ACTIVE: "1"
[Wed Nov 22 17:37:27] [gsm.modem0] Init: usbnet
[Wed Nov 22 17:37:27] [gsm.modem0] Init: nat settings
[Wed Nov 22 17:37:27] [gsm.modem0] GSM error in "generic_nat_set_cb:309" (Called out method not supported), gsmd_method_id: "81"
[Wed Nov 22 17:37:27] [gsm.modem0] Init: auth bit
[Wed Nov 22 17:37:27] [gsm.modem0] GSM error in "generic_set_auth_bit:337" (Called out method not supported), gsmd_method_id: "149"
[Wed Nov 22 17:37:27] [gsm.modem0] Init: mgcfg
[Wed Nov 22 17:37:27] [gsm.modem0] GSM error in "generic_mgcfg_cb:366" (Called out method not supported), gsmd_method_id: "103"
[Wed Nov 22 17:37:27] [gsm.modem0] Init: msg fmt
[Wed Nov 22 17:37:27] [gsm.modem0] Init: urc port
[Wed Nov 22 17:37:27] [gsm.modem0] Init: signal query
[Wed Nov 22 17:37:27] [gsm.modem0] Init: sim state
[Wed Nov 22 17:37:27] [gsm.modem0] GSM error in "generic_sim_state_cfg_cb:483" (Called out method not supported)
[Wed Nov 22 17:37:27] [gsm.modem0] Init: sms mode
[Wed Nov 22 17:37:27] [gsm.modem0] Init: roaming svc
[Wed Nov 22 17:37:27] [gsm.modem0] Init: m2m_func
[Wed Nov 22 17:37:27] [gsm.modem0] GSM error in "generic_set_m2m_state:688" (Called out method not supported), gsmd_method_id: "96"
[Wed Nov 22 17:37:27] [gsm.modem0] Init: urc_cause
[Wed Nov 22 17:37:27] [gsm.modem0] GSM error in "generic_enable_urc_cause:532" (Called out method not supported), gsmd_method_id: "161"
[Wed Nov 22 17:37:27] [gsm.modem0] Init: modem_func
[Wed Nov 22 17:37:27] [gsm.modem0] Modem is in dynamic operation mode
[Wed Nov 22 17:37:27] [gsm.modem0] Init: sms_storage_check
[Wed Nov 22 17:37:27] [gsm.modem0] Init: set_urc_ind_cfg
[Wed Nov 22 17:37:27] [gsm.modem0] Modem is already in CFUN: "2"(full) state
[Wed Nov 22 17:37:27] [gsm.modem0] Init: functionality
[Wed Nov 22 17:37:27] [gsm.modem0] Modem is already in CFUN: "2"(full) state
[Wed Nov 22 17:37:27] [gsm.modem0] Init: net reg
[Wed Nov 22 17:37:27] [gsm.modem0] Init: net greg
[Wed Nov 22 17:37:27] [gsm.modem0] Init: net ereg
[Wed Nov 22 17:37:27] [gsm.modem0] Init: net c5greg
[Wed Nov 22 17:37:27] [gsm.modem0] GSM error in "mb_set_net_reg:603" (Called out method not supported), gsmd_method_id: "24"
[Wed Nov 22 17:37:27] [gsm.modem0] Init: clip mode
[Wed Nov 22 17:37:27] [gsm.modem0] Init: CRC mode
[Wed Nov 22 17:37:27] [gsm.modem0] Init: CNMI mode
[Wed Nov 22 17:37:27] [gsm.modem0] Scheduling init process 'CNMI mode' for "200ms"
[Wed Nov 22 17:37:27] [gsm.modem0] Modem is already in CFUN: "2"(full) state
[Wed Nov 22 17:37:27] [gsm.modem0] Init process was sheduled, last init entry executed 'CNMI mode'
[Wed Nov 22 17:37:27] [gsm.modem0] Init: net mode
[Wed Nov 22 17:37:27] [gsm.modem0] Will use "auto" service mode.
[Wed Nov 22 17:37:27] [gsm.modem0] Modem is in correct service mode "Auto"
[Wed Nov 22 17:37:27] [gsm.modem0] GSM error in "set_network_category:258" (Called out method not supported), gsmd_method_id: "114"
[Wed Nov 22 17:37:27] [gsm.modem0] Network category "m1_and_nb1" is not supported. Network category won't be changed
[Wed Nov 22 17:37:27] [gsm.modem0] Scheduling init process 'net mode' for "1500ms"
[Wed Nov 22 17:37:28] [gsm.modem0] Modem is already in CFUN: "2"(full) state
[Wed Nov 22 17:37:28] [gsm.modem0] Init process was sheduled, last init entry executed 'net mode'
[Wed Nov 22 17:37:28] [gsm.modem0] Init: default bands
[Wed Nov 22 17:37:28] [gsm.modem0] Init: SMS storage
[Wed Nov 22 17:37:29] [gsm.modem0] SMS storage: "sm"
[Wed Nov 22 17:37:29] [gsm.modem0] Modem settings init completed
[Wed Nov 22 17:37:29] [gsm.modem0] Starting connection setup
[Wed Nov 22 17:37:29] Touch: /tmp/vuci/reload_routes
[Wed Nov 22 17:37:29] [gsm.modem0] Getting APN list
[Wed Nov 22 17:37:29] [gsm.modem0] Trying to select APN with "ipv4v6" pdptype
[Wed Nov 22 17:37:29] [gsm.modem0] Will use "" APN with "ipv4v6" pdptype
[Wed Nov 22 17:37:29] Registering event handler 'mob1s1a1'
[Wed Nov 22 17:37:29] [gsm.modem0] APN "IMS" is already set for PDP 2
[Wed Nov 22 17:37:29] [gsm.modem0] Network registration status received: "0"(Not registered)
[Wed Nov 22 17:37:29] [gsm.modem0] Mobile state: "Setting up connection settings"
[Wed Nov 22 17:37:29] [gsm.modem0] Modem is already in CFUN: "2"(full) state
[Wed Nov 22 17:37:29] [gsm.modem0] Preparing connection for 'mob1s1a1' interface
[Wed Nov 22 17:37:29] [gsm.modem0] Skipping first APN to not overwrite APN for 'mob1s1a1' interface
[Wed Nov 22 17:37:29] [gsm.modem0] APN "" is already set for PDP 1
[Wed Nov 22 17:37:29] [gsm.modem0] Attempting to establish connection to operator with "15s" of timeout
[Wed Nov 22 17:37:29] [gsm.modem0] timeout: "15" get_timeout: "15"
[Wed Nov 22 17:37:29] [gsm.modem0] Modem is already in COPS: "1"(Auto) state
[Wed Nov 22 17:37:29] [gsm.modem0] Testing connection to "" operator
[Wed Nov 22 17:37:29] [gsm.modem0] Network registration status received: "2"(Searching)
[Wed Nov 22 17:37:29] [gsm.modem0] Mobile state: "Setting up connection settings"
[Wed Nov 22 17:37:30] [gsm.modem0] Network registration status received: "3"(Denied)
[Wed Nov 22 17:37:30] [gsm.modem0] Mobile state: "Setting up connection settings"
[Wed Nov 22 17:37:30] Touch: /tmp/vuci/denied_event_3-1
[Wed Nov 22 17:37:30] [gsm.modem0] Network registration status received: "1"(Registered, home)
[Wed Nov 22 17:37:30] [gsm.modem0] Mobile state: "Setting up connection settings"
[Wed Nov 22 17:37:30] Touch: /tmp/vuci/reload_routes
[Wed Nov 22 17:37:34] [gsm.modem0] timeout: "10" get_timeout: "15"
[Wed Nov 22 17:37:34] [gsm.modem0] Testing connection to "" operator
[Wed Nov 22 17:37:34] [gsm.modem0] Connected to operator "413 12 Sri Lanka SLT"
[Wed Nov 22 17:37:34] [gsm.modem0] Will use "auto" service mode.
[Wed Nov 22 17:37:34] [gsm.modem0] Modem is in correct service mode "Auto"
[Wed Nov 22 17:37:34] [gsm.modem0] GSM error in "set_network_category:258" (Called out method not supported), gsmd_method_id: "114"
[Wed Nov 22 17:37:34] [gsm.modem0] Network category "m1_and_nb1" is not supported. Network category won't be changed
[Wed Nov 22 17:37:34] [gsm.modem0] MBN: "ROW_Generic_3GPP", SELECTED: "1", ACTIVE: "1"
[Wed Nov 22 17:37:34] [gsm.modem0] MBN: "ROW_Generic_3GPP" saved to 'simcard' config
[Wed Nov 22 17:37:34] [gsm.modem0] -CFUN- Functionality: "Full"
[Wed Nov 22 17:37:34] [gsm.modem0] -COPS- Mode: "Auto", operator: "413 12 Sri Lanka SLT"
[Wed Nov 22 17:37:34] [gsm.modem0] -CREG- Mode: "Enabled (with location information)", status: "Denied", LAC: "", cell ID: "", technology: "Unknown"
[Wed Nov 22 17:37:34] [gsm.modem0] -CGREG- Mode: "Enabled (with location information)", status: "Registered, home", LAC: "201", cell ID: "5223681", technology: "E-UTRAN"
[Wed Nov 22 17:37:34] [gsm.modem0] -CEREG- Mode: "Enabled (with location information)", status: "Registered, home", LAC: "201", cell ID: "5223681", technology: "E-UTRAN"
[Wed Nov 22 17:37:34] [gsm.modem0] GSM error in "mb_print_reg_status:723" (Called out method not supported), gsmd_method_id: "24"
[Wed Nov 22 17:37:34] [gsm.modem0] IP address on PDP context "1": "100.104.114.116"
[Wed Nov 22 17:37:34] [gsm.modem0] Checking IP address on PDP context "1"
[Wed Nov 22 17:37:34] [gsm.modem0] PDP type on PDP context "1" is correct
[Wed Nov 22 17:37:34] Saving APN information in "network" config for 'mob1s1a1' interface
[Wed Nov 22 17:37:34] Reloading network

[Wed Nov 22 17:37:34] Checking network.interface.'mob1s1a1' ubus ID
[Wed Nov 22 17:37:34] Interface 'mob1s1a1' is going up
[Wed Nov 22 17:37:34] Setting IFUP timeout for 'mob1s1a1' of "120s"
[Wed Nov 22 17:37:40] Network event received "ifup" for 'mob1s1a1_4'
[Wed Nov 22 17:37:41] Sending PING "qmimux0" -> "8.8.8.8"
[Wed Nov 22 17:37:41] Sending PING "qmimux0" -> "8.8.8.8"
[Wed Nov 22 17:37:42] PING to "8.8.8.8" passed
[Wed Nov 22 17:37:42] [gsm.modem0] VoLTE: "Inactive"
[Wed Nov 22 17:37:42] Connection setup completed for 'mob1s1a1'
7

Hello,

While the disconnection event is not visible in the logs, I can see that the device cannot connect with the default APN to your operator. Please navigate to Network → WAN → edit the mob1s1a1 interface, disable the Auto APN feature and set the correct APN provided by your carrier. It may not eliminate the disconnection issue, but it should reduce the reconnection times, which may also help mitigate the original issue.
It could be that your operator simply refuses to renew your lease, which results in a reconnection, and as the APN is not set it takes quite a while to reconnect.
Let me know how it goes!

Best regards,

8

Hi @Daumantas thanks for the reply I searched my current provider APN settings but cannot find it anywhere. Mostly they mentioned that use the APN settings AUTO or DEFAULT for their TDD network. I checked the default huawei router as well and there’s no APN configured

Also when I reboot the router it will stay stable for few hours and then again start keep flapping the wan interface. Here another set of logs that I captured when it disconnect. I added it to zabbix monitoring to make sure I get notified when the wan interface go down.

[Thu Nov 23 13:37:17] Checking network.interface.'mob1s2a1' ubus ID
[Thu Nov 23 13:37:17] Interface 'mob1s2a1' is going up
[Thu Nov 23 13:37:17] Setting IFUP timeout for 'mob1s2a1' of "120s"
[Thu Nov 23 13:37:20] Network event received "ifup" for 'mob1s2a1_4'
[Thu Nov 23 13:37:21] Sending PING "qmimux0" -> "8.8.8.8"
[Thu Nov 23 13:37:22] PING to "8.8.8.8" passed
[Thu Nov 23 13:37:22] [gsm.modem0] VoLTE: "Inactive"
[Thu Nov 23 13:37:22] Connection setup completed for 'mob1s2a1'
[Thu Nov 23 13:42:15] [gsm.modem0] Network registration status received: "0"(Not registered)
[Thu Nov 23 13:42:15] [gsm.modem0] Mobile state: "Mobile connection setup is complete"
[Thu Nov 23 13:42:15] [3-1] sim_change_cb new SIM: 1
[Thu Nov 23 13:42:15] [gsm.modem0] Default SIM change initiated
[Thu Nov 23 13:42:15] [gsm.modem0] Modem config reload will be executed due to a SIM switch event
[Thu Nov 23 13:42:15] Checking network.interface.'mob1s2a1' ubus ID
[Thu Nov 23 13:42:15] Interface 'mob1s2a1' going down
[Thu Nov 23 13:42:15] [gsm.modem0] Cancelling uloop timeouts
[Thu Nov 23 13:42:20] [gsm.modem0] PIN state event: "SIM not inserted"
[Thu Nov 23 13:42:20] [gsm.modem0] Mobile state: "Currently handling SIM switch event"
[Thu Nov 23 13:42:25] [gsm.modem0] Performing config reload
[Thu Nov 23 13:42:25] [gsm.modem0] Config reload initiated
[Thu Nov 23 13:42:25] Checking network.interface.'mob1s2a1' ubus ID
[Thu Nov 23 13:42:25] Interface 'mob1s2a1' going down
[Thu Nov 23 13:42:25] [gsm.modem0] Cancelling uloop timeouts
[Thu Nov 23 13:42:25] [gsm.modem0] Active SIM: "1"
[Thu Nov 23 13:42:25] [gsm.modem0] Setting CFUN: "2"(full)
[Thu Nov 23 13:42:29] [gsm.modem0] SIM is ready
[Thu Nov 23 13:42:29] [gsm.modem0] Init: functionality
[Thu Nov 23 13:42:29] [gsm.modem0] Modem is already in CFUN: "2"(full) state
[Thu Nov 23 13:42:29] [gsm.modem0] Init: dhcp filter
[Thu Nov 23 13:42:29] [gsm.modem0] Init: ue usage
[Thu Nov 23 13:42:29] [gsm.modem0] GSM error in "generic_ue_usage_cb:73" (Called out method not supported), gsmd_method_id: "83"
[Thu Nov 23 13:42:29] [gsm.modem0] Init: mbn_autosel
[Thu Nov 23 13:42:29] [gsm.modem0] Init: auto_timezone
[Thu Nov 23 13:42:29] [gsm.modem0] Init: mbn
[Thu Nov 23 13:42:29] [gsm.modem0] MBN: "ROW_Generic_3GPP", SELECTED: "1", ACTIVE: "1"
[Thu Nov 23 13:42:29] [gsm.modem0] Init: usbnet
[Thu Nov 23 13:42:29] [gsm.modem0] Init: nat settings
[Thu Nov 23 13:42:29] [gsm.modem0] GSM error in "generic_nat_set_cb:309" (Called out method not supported), gsmd_method_id: "81"
[Thu Nov 23 13:42:29] [gsm.modem0] Init: auth bit
[Thu Nov 23 13:42:29] [gsm.modem0] GSM error in "generic_set_auth_bit:337" (Called out method not supported), gsmd_method_id: "149"
[Thu Nov 23 13:42:29] [gsm.modem0] Init: mgcfg
[Thu Nov 23 13:42:29] [gsm.modem0] GSM error in "generic_mgcfg_cb:366" (Called out method not supported), gsmd_method_id: "103"
[Thu Nov 23 13:42:29] [gsm.modem0] Init: msg fmt
[Thu Nov 23 13:42:29] [gsm.modem0] Init: urc port
[Thu Nov 23 13:42:29] [gsm.modem0] Init: signal query
[Thu Nov 23 13:42:29] [gsm.modem0] Init: sim state
[Thu Nov 23 13:42:29] [gsm.modem0] GSM error in "generic_sim_state_cfg_cb:483" (Called out method not supported)
[Thu Nov 23 13:42:29] [gsm.modem0] Init: sms mode
[Thu Nov 23 13:42:29] [gsm.modem0] Init: roaming svc
[Thu Nov 23 13:42:29] [gsm.modem0] Init: m2m_func
[Thu Nov 23 13:42:29] [gsm.modem0] GSM error in "generic_set_m2m_state:688" (Called out method not supported), gsmd_method_id: "96"
[Thu Nov 23 13:42:29] [gsm.modem0] Init: urc_cause
[Thu Nov 23 13:42:29] [gsm.modem0] GSM error in "generic_enable_urc_cause:532" (Called out method not supported), gsmd_method_id: "161"
[Thu Nov 23 13:42:29] [gsm.modem0] Init: modem_func
[Thu Nov 23 13:42:29] [gsm.modem0] Modem is in dynamic operation mode
[Thu Nov 23 13:42:29] [gsm.modem0] Init: sms_storage_check
[Thu Nov 23 13:42:29] [gsm.modem0] Init: set_urc_ind_cfg
[Thu Nov 23 13:42:29] [gsm.modem0] Modem is already in CFUN: "2"(full) state
[Thu Nov 23 13:42:29] [gsm.modem0] Init: functionality
[Thu Nov 23 13:42:29] [gsm.modem0] Modem is already in CFUN: "2"(full) state
[Thu Nov 23 13:42:29] [gsm.modem0] Init: net reg
[Thu Nov 23 13:42:29] [gsm.modem0] Init: net greg
[Thu Nov 23 13:42:29] [gsm.modem0] Init: net ereg
[Thu Nov 23 13:42:29] [gsm.modem0] Init: net c5greg
[Thu Nov 23 13:42:29] [gsm.modem0] GSM error in "mb_set_net_reg:603" (Called out method not supported), gsmd_method_id: "24"
[Thu Nov 23 13:42:29] [gsm.modem0] Init: clip mode
[Thu Nov 23 13:42:29] [gsm.modem0] Init: CRC mode
[Thu Nov 23 13:42:29] [gsm.modem0] Init: CNMI mode
[Thu Nov 23 13:42:29] [gsm.modem0] Scheduling init process 'CNMI mode' for "200ms"
[Thu Nov 23 13:42:29] [gsm.modem0] PIN state event: "OK"
[Thu Nov 23 13:42:29] [gsm.modem0] Mobile state: "Initializing modem"
[Thu Nov 23 13:42:29] [gsm.modem0] Modem will be reloaded due to PIN state "OK"
[Thu Nov 23 13:42:29] [gsm.modem0] Config reload initiated
[Thu Nov 23 13:42:29] [gsm.modem0] Cancelling uloop timeouts
[Thu Nov 23 13:42:29] [gsm.modem0] Active SIM: "1"
[Thu Nov 23 13:42:29] [gsm.modem0] Network registration status received: "2"(Searching)
[Thu Nov 23 13:42:29] [gsm.modem0] Mobile state: "Initializing modem"
[Thu Nov 23 13:42:29] [gsm.modem0] Network registration status received: "0"(Not registered)
[Thu Nov 23 13:42:29] [gsm.modem0] Mobile state: "Initializing modem"
[Thu Nov 23 13:42:29] [gsm.modem0] Modem is already in CFUN: "2"(full) state
[Thu Nov 23 13:42:29] [gsm.modem0] SIM is ready
[Thu Nov 23 13:42:29] [gsm.modem0] Init: functionality
[Thu Nov 23 13:42:29] [gsm.modem0] Modem is already in CFUN: "2"(full) state
[Thu Nov 23 13:42:29] [gsm.modem0] Init: dhcp filter
[Thu Nov 23 13:42:29] [gsm.modem0] Init: ue usage
[Thu Nov 23 13:42:29] [gsm.modem0] GSM error in "generic_ue_usage_cb:73" (Called out method not supported), gsmd_method_id: "83"
[Thu Nov 23 13:42:29] [gsm.modem0] Init: mbn_autosel
[Thu Nov 23 13:42:29] [gsm.modem0] Init: auto_timezone
[Thu Nov 23 13:42:29] [gsm.modem0] Init: mbn
[Thu Nov 23 13:42:29] [gsm.modem0] MBN: "ROW_Generic_3GPP", SELECTED: "1", ACTIVE: "1"
[Thu Nov 23 13:42:29] [gsm.modem0] Init: usbnet
[Thu Nov 23 13:42:29] [gsm.modem0] Init: nat settings
[Thu Nov 23 13:42:29] [gsm.modem0] GSM error in "generic_nat_set_cb:309" (Called out method not supported), gsmd_method_id: "81"
[Thu Nov 23 13:42:29] [gsm.modem0] Init: auth bit
[Thu Nov 23 13:42:29] [gsm.modem0] GSM error in "generic_set_auth_bit:337" (Called out method not supported), gsmd_method_id: "149"
[Thu Nov 23 13:42:29] [gsm.modem0] Init: mgcfg
[Thu Nov 23 13:42:29] [gsm.modem0] GSM error in "generic_mgcfg_cb:366" (Called out method not supported), gsmd_method_id: "103"
[Thu Nov 23 13:42:29] [gsm.modem0] Init: msg fmt
[Thu Nov 23 13:42:29] [gsm.modem0] Init: urc port
[Thu Nov 23 13:42:29] [gsm.modem0] Init: signal query
[Thu Nov 23 13:42:29] [gsm.modem0] Init: sim state
[Thu Nov 23 13:42:29] [gsm.modem0] GSM error in "generic_sim_state_cfg_cb:483" (Called out method not supported)
[Thu Nov 23 13:42:29] [gsm.modem0] Init: sms mode
[Thu Nov 23 13:42:29] [gsm.modem0] Init: roaming svc
[Thu Nov 23 13:42:29] [gsm.modem0] Init: m2m_func
[Thu Nov 23 13:42:29] [gsm.modem0] GSM error in "generic_set_m2m_state:688" (Called out method not supported), gsmd_method_id: "96"
[Thu Nov 23 13:42:29] [gsm.modem0] Init: urc_cause
[Thu Nov 23 13:42:29] [gsm.modem0] GSM error in "generic_enable_urc_cause:532" (Called out method not supported), gsmd_method_id: "161"
[Thu Nov 23 13:42:29] [gsm.modem0] Init: modem_func
[Thu Nov 23 13:42:29] [gsm.modem0] Modem is in dynamic operation mode
[Thu Nov 23 13:42:29] [gsm.modem0] Init: sms_storage_check
[Thu Nov 23 13:42:29] [gsm.modem0] Init: set_urc_ind_cfg
[Thu Nov 23 13:42:29] [gsm.modem0] Modem is already in CFUN: "2"(full) state
[Thu Nov 23 13:42:29] [gsm.modem0] Init: functionality
[Thu Nov 23 13:42:29] [gsm.modem0] Modem is already in CFUN: "2"(full) state
[Thu Nov 23 13:42:29] [gsm.modem0] Init: net reg
[Thu Nov 23 13:42:29] [gsm.modem0] Init: net greg
[Thu Nov 23 13:42:29] [gsm.modem0] Init: net ereg
[Thu Nov 23 13:42:29] [gsm.modem0] Init: net c5greg
[Thu Nov 23 13:42:29] [gsm.modem0] GSM error in "mb_set_net_reg:603" (Called out method not supported), gsmd_method_id: "24"
[Thu Nov 23 13:42:29] [gsm.modem0] Init: clip mode
[Thu Nov 23 13:42:29] [gsm.modem0] Init: CRC mode
[Thu Nov 23 13:42:29] [gsm.modem0] Init: CNMI mode
[Thu Nov 23 13:42:29] [gsm.modem0] Scheduling init process 'CNMI mode' for "200ms"
[Thu Nov 23 13:42:29] [gsm.modem0] Modem is already in CFUN: "2"(full) state
[Thu Nov 23 13:42:29] [gsm.modem0] Init process was sheduled, last init entry executed 'CNMI mode'
[Thu Nov 23 13:42:29] [gsm.modem0] Init: net mode
[Thu Nov 23 13:42:29] [gsm.modem0] Will use "auto" service mode.
[Thu Nov 23 13:42:29] [gsm.modem0] Modem is in correct service mode "Auto"
[Thu Nov 23 13:42:29] [gsm.modem0] GSM error in "set_network_category:258" (Called out method not supported), gsmd_method_id: "114"
[Thu Nov 23 13:42:29] [gsm.modem0] Network category "m1_and_nb1" is not supported. Network category won't be changed
[Thu Nov 23 13:42:29] [gsm.modem0] Scheduling init process 'net mode' for "1500ms"
[Thu Nov 23 13:42:31] [gsm.modem0] Modem is already in CFUN: "2"(full) state
[Thu Nov 23 13:42:31] [gsm.modem0] Init process was sheduled, last init entry executed 'net mode'
[Thu Nov 23 13:42:31] [gsm.modem0] Init: default bands
[Thu Nov 23 13:42:31] [gsm.modem0] Init: SMS storage
[Thu Nov 23 13:42:31] [gsm.modem0] SMS storage: "sm"
[Thu Nov 23 13:42:31] [gsm.modem0] Modem settings init completed
[Thu Nov 23 13:42:31] [gsm.modem0] Starting connection setup
[Thu Nov 23 13:42:31] Touch: /tmp/vuci/reload_routes
[Thu Nov 23 13:42:31] [gsm.modem0] Getting APN list
[Thu Nov 23 13:42:31] [gsm.modem0] Trying to select APN with "ipv4v6" pdptype
[Thu Nov 23 13:42:31] [gsm.modem0] Will use "" APN with "ipv4v6" pdptype
[Thu Nov 23 13:42:31] Registering event handler 'mob1s1a1'
[Thu Nov 23 13:42:31] [gsm.modem0] APN "IMS" is already set for PDP 2
[Thu Nov 23 13:42:31] [gsm.modem0] Modem is already in CFUN: "2"(full) state
[Thu Nov 23 13:42:31] [gsm.modem0] Preparing connection for 'mob1s1a1' interface
[Thu Nov 23 13:42:31] [gsm.modem0] Skipping first APN to not overwrite APN for 'mob1s1a1' interface
[Thu Nov 23 13:42:31] [gsm.modem0] APN "" isn't prepared for PDP "1", preparing it
[Thu Nov 23 13:42:31] [gsm.modem0] Setting CFUN: "3"(rf)
[Thu Nov 23 13:42:31] [gsm.modem0] Setting CFUN: "2"(full)
[Thu Nov 23 13:42:34] [gsm.modem0] APN "" was successfully prepared for PDP "1"
[Thu Nov 23 13:42:34] [gsm.modem0] Attempting to establish connection to operator with "15s" of timeout
[Thu Nov 23 13:42:34] [gsm.modem0] Network registration status received: "2"(Searching)
[Thu Nov 23 13:42:34] [gsm.modem0] Mobile state: "Setting up connection settings"
[Thu Nov 23 13:42:34] [gsm.modem0] timeout: "15" get_timeout: "15"
[Thu Nov 23 13:42:34] [gsm.modem0] Modem is already in COPS: "1"(Auto) state
[Thu Nov 23 13:42:34] [gsm.modem0] Testing connection to "" operator
[Thu Nov 23 13:42:34] [gsm.modem0] Network registration status received: "0"(Not registered)
[Thu Nov 23 13:42:34] [gsm.modem0] Mobile state: "Setting up connection settings"
[Thu Nov 23 13:42:34] [gsm.modem0] Network registration status received: "2"(Searching)
[Thu Nov 23 13:42:34] [gsm.modem0] Mobile state: "Setting up connection settings"
[Thu Nov 23 13:42:39] [gsm.modem0] timeout: "10" get_timeout: "15"
[Thu Nov 23 13:42:39] [gsm.modem0] Testing connection to "" operator
[Thu Nov 23 13:42:44] [gsm.modem0] timeout: "5" get_timeout: "15"
[Thu Nov 23 13:42:44] [gsm.modem0] Testing connection to "" operator
[Thu Nov 23 13:42:49] [gsm.modem0] timeout: "0" get_timeout: "15"
[Thu Nov 23 13:42:49] [gsm.modem0] Testing connection to "" operator
[Thu Nov 23 13:42:49] [gsm.modem0] timeout: "15" get_timeout: "15"
[Thu Nov 23 13:42:49] [gsm.modem0] Auto connection setup done. Try again
[Thu Nov 23 13:43:04] [gsm.modem0] Modem is already in CFUN: "2"(full) state
[Thu Nov 23 13:43:04] [gsm.modem0] Preparing connection for 'mob1s1a1' interface
[Thu Nov 23 13:43:04] [gsm.modem0] Trying to select APN with "ipv4v6" pdptype
[Thu Nov 23 13:43:04] [gsm.modem0] APN not found in database, selecting empty APN from list_first_entry
[Thu Nov 23 13:43:04] [gsm.modem0] Will use "" APN with "ipv4v6" pdptype
[Thu Nov 23 13:43:04] Touch: /tmp/vuci/all_apns_tried_mob1s1a1
[Thu Nov 23 13:43:04] [gsm.modem0] All APNs were used, increasing timeout to 30s
[Thu Nov 23 13:43:04] [gsm.modem0] APN "" is already set for PDP 1
[Thu Nov 23 13:43:04] [gsm.modem0] Attempting to establish connection to operator with "30s" of timeout
[Thu Nov 23 13:43:04] [gsm.modem0] timeout: "30" get_timeout: "30"
[Thu Nov 23 13:43:04] [gsm.modem0] Modem is already in COPS: "1"(Auto) state
[Thu Nov 23 13:43:04] [gsm.modem0] Testing connection to "" operator
[Thu Nov 23 13:43:09] [gsm.modem0] timeout: "25" get_timeout: "30"
[Thu Nov 23 13:43:09] [gsm.modem0] Testing connection to "" operator
[Thu Nov 23 13:43:14] [gsm.modem0] timeout: "20" get_timeout: "30"
[Thu Nov 23 13:43:14] [gsm.modem0] Testing connection to "" operator
[Thu Nov 23 13:43:19] [gsm.modem0] timeout: "15" get_timeout: "30"
[Thu Nov 23 13:43:19] [gsm.modem0] Testing connection to "" operator
[Thu Nov 23 13:43:24] [gsm.modem0] timeout: "10" get_timeout: "30"
[Thu Nov 23 13:43:24] [gsm.modem0] Testing connection to "" operator
[Thu Nov 23 13:43:29] [gsm.modem0] timeout: "5" get_timeout: "30"
[Thu Nov 23 13:43:29] [gsm.modem0] Testing connection to "" operator
[Thu Nov 23 13:43:34] [gsm.modem0] timeout: "0" get_timeout: "30"
[Thu Nov 23 13:43:34] [gsm.modem0] Testing connection to "" operator
[Thu Nov 23 13:43:34] [gsm.modem0] timeout: "30" get_timeout: "30"
[Thu Nov 23 13:43:34] [gsm.modem0] Auto connection setup done. Try again
[Thu Nov 23 13:44:04] [gsm.modem0] Modem is already in CFUN: "2"(full) state
[Thu Nov 23 13:44:04] [gsm.modem0] Preparing connection for 'mob1s1a1' interface
[Thu Nov 23 13:44:04] [gsm.modem0] Trying to select APN with "ipv4v6" pdptype
[Thu Nov 23 13:44:04] [gsm.modem0] APN not found in database, selecting empty APN from list_first_entry
[Thu Nov 23 13:44:04] [gsm.modem0] Will use "" APN with "ipv4v6" pdptype
[Thu Nov 23 13:44:04] Touch: /tmp/vuci/all_apns_tried_mob1s1a1
[Thu Nov 23 13:44:04] [gsm.modem0] All APNs were used, increasing timeout to 60s
[Thu Nov 23 13:44:04] [gsm.modem0] APN "" is already set for PDP 1
[Thu Nov 23 13:44:04] [gsm.modem0] Attempting to establish connection to operator with "60s" of timeout
[Thu Nov 23 13:44:04] [gsm.modem0] timeout: "60" get_timeout: "60"
[Thu Nov 23 13:44:04] [gsm.modem0] Modem is already in COPS: "1"(Auto) state
[Thu Nov 23 13:44:04] [gsm.modem0] Testing connection to "" operator
[Thu Nov 23 13:44:09] [gsm.modem0] timeout: "55" get_timeout: "60"
[Thu Nov 23 13:44:09] [gsm.modem0] Testing connection to "" operator
[Thu Nov 23 13:44:14] [gsm.modem0] timeout: "50" get_timeout: "60"
[Thu Nov 23 13:44:14] [gsm.modem0] Testing connection to "" operator
[Thu Nov 23 13:44:19] [gsm.modem0] timeout: "45" get_timeout: "60"
[Thu Nov 23 13:44:19] [gsm.modem0] Testing connection to "" operator
[Thu Nov 23 13:44:24] [gsm.modem0] timeout: "40" get_timeout: "60"
[Thu Nov 23 13:44:24] [gsm.modem0] Testing connection to "" operator
[Thu Nov 23 13:44:29] [gsm.modem0] timeout: "35" get_timeout: "60"
[Thu Nov 23 13:44:29] [gsm.modem0] Testing connection to "" operator
[Thu Nov 23 13:44:34] [gsm.modem0] timeout: "30" get_timeout: "60"
[Thu Nov 23 13:44:34] [gsm.modem0] Testing connection to "" operator
[Thu Nov 23 13:44:36] [gsm.modem0] Network registration status received: "0"(Not registered)
[Thu Nov 23 13:44:36] [gsm.modem0] Mobile state: "Setting up connection settings"
[Thu Nov 23 13:44:36] [3-1] sim_change_cb new SIM: 2
[Thu Nov 23 13:44:36] [gsm.modem0] Default SIM change initiated
[Thu Nov 23 13:44:36] [gsm.modem0] Modem config reload will be executed due to a SIM switch event
[Thu Nov 23 13:44:36] Checking network.interface.'mob1s1a1' ubus ID
[Thu Nov 23 13:44:36] Interface 'mob1s1a1' going down
[Thu Nov 23 13:44:36] [gsm.modem0] Cancelling uloop timeouts
[Thu Nov 23 13:44:36] [gsm.modem0] PIN state event: "SIM not inserted"
[Thu Nov 23 13:44:36] [gsm.modem0] Mobile state: "Currently handling SIM switch event"
[Thu Nov 23 13:44:46] [gsm.modem0] Performing config reload
[Thu Nov 23 13:44:46] [gsm.modem0] Config reload initiated
[Thu Nov 23 13:44:46] Checking network.interface.'mob1s1a1' ubus ID
[Thu Nov 23 13:44:46] Interface 'mob1s1a1' going down
[Thu Nov 23 13:44:46] [gsm.modem0] Cancelling uloop timeouts
[Thu Nov 23 13:44:46] [gsm.modem0] Active SIM: "2"
[Thu Nov 23 13:44:46] [gsm.modem0] Setting CFUN: "2"(full)
[Thu Nov 23 13:44:49] [gsm.modem0] SIM is ready
[Thu Nov 23 13:44:49] [gsm.modem0] Init: functionality
[Thu Nov 23 13:44:49] [gsm.modem0] Modem is already in CFUN: "2"(full) state
[Thu Nov 23 13:44:49] [gsm.modem0] Init: dhcp filter
[Thu Nov 23 13:44:49] [gsm.modem0] Init: ue usage
[Thu Nov 23 13:44:49] [gsm.modem0] GSM error in "generic_ue_usage_cb:73" (Called out method not supported), gsmd_method_id: "83"
[Thu Nov 23 13:44:49] [gsm.modem0] Init: mbn_autosel
[Thu Nov 23 13:44:49] [gsm.modem0] Init: auto_timezone
[Thu Nov 23 13:44:49] [gsm.modem0] Init: mbn
[Thu Nov 23 13:44:49] [gsm.modem0] MBN: "ROW_Generic_3GPP", SELECTED: "1", ACTIVE: "1"
[Thu Nov 23 13:44:49] [gsm.modem0] Init: usbnet
[Thu Nov 23 13:44:49] [gsm.modem0] Init: nat settings
[Thu Nov 23 13:44:49] [gsm.modem0] GSM error in "generic_nat_set_cb:309" (Called out method not supported), gsmd_method_id: "81"
[Thu Nov 23 13:44:49] [gsm.modem0] Init: auth bit
[Thu Nov 23 13:44:49] [gsm.modem0] GSM error in "generic_set_auth_bit:337" (Called out method not supported), gsmd_method_id: "149"
[Thu Nov 23 13:44:49] [gsm.modem0] Init: mgcfg
[Thu Nov 23 13:44:49] [gsm.modem0] GSM error in "generic_mgcfg_cb:366" (Called out method not supported), gsmd_method_id: "103"
[Thu Nov 23 13:44:49] [gsm.modem0] Init: msg fmt
[Thu Nov 23 13:44:49] [gsm.modem0] Init: urc port
[Thu Nov 23 13:44:49] [gsm.modem0] Init: signal query
[Thu Nov 23 13:44:49] [gsm.modem0] Init: sim state
[Thu Nov 23 13:44:49] [gsm.modem0] GSM error in "generic_sim_state_cfg_cb:483" (Called out method not supported)
[Thu Nov 23 13:44:49] [gsm.modem0] Init: sms mode
[Thu Nov 23 13:44:49] [gsm.modem0] Init: roaming svc
[Thu Nov 23 13:44:49] [gsm.modem0] Init: m2m_func
[Thu Nov 23 13:44:49] [gsm.modem0] GSM error in "generic_set_m2m_state:688" (Called out method not supported), gsmd_method_id: "96"
[Thu Nov 23 13:44:49] [gsm.modem0] Init: urc_cause
[Thu Nov 23 13:44:49] [gsm.modem0] GSM error in "generic_enable_urc_cause:532" (Called out method not supported), gsmd_method_id: "161"
[Thu Nov 23 13:44:49] [gsm.modem0] Init: modem_func
[Thu Nov 23 13:44:49] [gsm.modem0] Modem is in dynamic operation mode
[Thu Nov 23 13:44:49] [gsm.modem0] Init: sms_storage_check
[Thu Nov 23 13:44:49] [gsm.modem0] GSM error in "mb_check_sms_storage:475" (Failed to parse response), gsmd_method_id: "63"
[Thu Nov 23 13:44:49] [gsm.modem0] Failed to get SMS storage
[Thu Nov 23 13:44:49] [gsm.modem0] Init 'sms_storage_check' failed
[Thu Nov 23 13:44:52] [gsm.modem0] Init: set_urc_ind_cfg
[Thu Nov 23 13:44:52] [gsm.modem0] Modem is already in CFUN: "2"(full) state
[Thu Nov 23 13:44:52] [gsm.modem0] Init: functionality
[Thu Nov 23 13:44:52] [gsm.modem0] Modem is already in CFUN: "2"(full) state
[Thu Nov 23 13:44:52] [gsm.modem0] Init: net reg
[Thu Nov 23 13:44:52] [gsm.modem0] Init: net greg
[Thu Nov 23 13:44:52] [gsm.modem0] Init: net ereg
[Thu Nov 23 13:44:52] [gsm.modem0] Init: net c5greg
[Thu Nov 23 13:44:52] [gsm.modem0] GSM error in "mb_set_net_reg:603" (Called out method not supported), gsmd_method_id: "24"
[Thu Nov 23 13:44:52] [gsm.modem0] Init: clip mode
[Thu Nov 23 13:44:52] [gsm.modem0] Init: CRC mode
[Thu Nov 23 13:44:52] [gsm.modem0] Init: CNMI mode
[Thu Nov 23 13:44:52] [gsm.modem0] Scheduling init process 'CNMI mode' for "200ms"
[Thu Nov 23 13:44:52] [gsm.modem0] PIN state event: "SIM busy"
[Thu Nov 23 13:44:52] [gsm.modem0] Mobile state: "Initializing modem"
[Thu Nov 23 13:44:52] [gsm.modem0] PIN state event: "OK"
[Thu Nov 23 13:44:52] [gsm.modem0] Mobile state: "Initializing modem"
[Thu Nov 23 13:44:52] [gsm.modem0] Modem will be reloaded due to PIN state "OK"
[Thu Nov 23 13:44:52] [gsm.modem0] Config reload initiated
[Thu Nov 23 13:44:52] [gsm.modem0] Cancelling uloop timeouts
[Thu Nov 23 13:44:52] [gsm.modem0] Active SIM: "2"
[Thu Nov 23 13:44:52] [gsm.modem0] Network registration status received: "2"(Searching)
[Thu Nov 23 13:44:52] [gsm.modem0] Mobile state: "Initializing modem"
[Thu Nov 23 13:44:52] [gsm.modem0] Network registration status received: "0"(Not registered)
[Thu Nov 23 13:44:52] [gsm.modem0] Mobile state: "Initializing modem"
[Thu Nov 23 13:44:52] [gsm.modem0] Network registration status received: "2"(Searching)
[Thu Nov 23 13:44:52] [gsm.modem0] Mobile state: "Initializing modem"
[Thu Nov 23 13:44:52] [gsm.modem0] Network registration status received: "1"(Registered, home)
[Thu Nov 23 13:44:52] [gsm.modem0] Mobile state: "Initializing modem"
[Thu Nov 23 13:44:52] Touch: /tmp/vuci/reload_routes
[Thu Nov 23 13:44:52] [gsm.modem0] Modem is already in CFUN: "2"(full) state
[Thu Nov 23 13:44:52] [gsm.modem0] SIM is ready
[Thu Nov 23 13:44:52] [gsm.modem0] Init: functionality
[Thu Nov 23 13:44:53] [gsm.modem0] Modem is already in CFUN: "2"(full) state
[Thu Nov 23 13:44:53] [gsm.modem0] Init: dhcp filter
[Thu Nov 23 13:44:53] [gsm.modem0] Init: ue usage
[Thu Nov 23 13:44:53] [gsm.modem0] GSM error in "generic_ue_usage_cb:73" (Called out method not supported), gsmd_method_id: "83"
[Thu Nov 23 13:44:53] [gsm.modem0] Init: mbn_autosel
[Thu Nov 23 13:44:53] [gsm.modem0] Init: auto_timezone
[Thu Nov 23 13:44:53] [gsm.modem0] Init: mbn
[Thu Nov 23 13:44:53] [gsm.modem0] MBN: "ROW_Generic_3GPP", SELECTED: "1", ACTIVE: "1"
[Thu Nov 23 13:44:53] [gsm.modem0] Init: usbnet
[Thu Nov 23 13:44:53] [gsm.modem0] Init: nat settings
[Thu Nov 23 13:44:53] [gsm.modem0] GSM error in "generic_nat_set_cb:309" (Called out method not supported), gsmd_method_id: "81"
[Thu Nov 23 13:44:53] [gsm.modem0] Init: auth bit
[Thu Nov 23 13:44:53] [gsm.modem0] GSM error in "generic_set_auth_bit:337" (Called out method not supported), gsmd_method_id: "149"
[Thu Nov 23 13:44:53] [gsm.modem0] Init: mgcfg
[Thu Nov 23 13:44:53] [gsm.modem0] GSM error in "generic_mgcfg_cb:366" (Called out method not supported), gsmd_method_id: "103"
[Thu Nov 23 13:44:53] [gsm.modem0] Init: msg fmt
[Thu Nov 23 13:44:53] [gsm.modem0] Init: urc port
[Thu Nov 23 13:44:53] [gsm.modem0] Init: signal query
[Thu Nov 23 13:44:53] [gsm.modem0] Init: sim state
[Thu Nov 23 13:44:53] [gsm.modem0] GSM error in "generic_sim_state_cfg_cb:483" (Called out method not supported)
[Thu Nov 23 13:44:53] [gsm.modem0] Init: sms mode
[Thu Nov 23 13:44:53] [gsm.modem0] Init: roaming svc
[Thu Nov 23 13:44:53] [gsm.modem0] Init: m2m_func
[Thu Nov 23 13:44:53] [gsm.modem0] GSM error in "generic_set_m2m_state:688" (Called out method not supported), gsmd_method_id: "96"
[Thu Nov 23 13:44:53] [gsm.modem0] Init: urc_cause
[Thu Nov 23 13:44:53] [gsm.modem0] GSM error in "generic_enable_urc_cause:532" (Called out method not supported), gsmd_method_id: "161"
[Thu Nov 23 13:44:53] [gsm.modem0] Init: modem_func
[Thu Nov 23 13:44:53] [gsm.modem0] Modem is in dynamic operation mode
[Thu Nov 23 13:44:53] [gsm.modem0] Init: sms_storage_check
[Thu Nov 23 13:44:53] [gsm.modem0] Init: set_urc_ind_cfg
[Thu Nov 23 13:44:53] [gsm.modem0] Modem is already in CFUN: "2"(full) state
[Thu Nov 23 13:44:53] [gsm.modem0] Init: functionality
[Thu Nov 23 13:44:53] [gsm.modem0] Modem is already in CFUN: "2"(full) state
[Thu Nov 23 13:44:53] [gsm.modem0] Init: net reg
[Thu Nov 23 13:44:53] [gsm.modem0] Init: net greg
[Thu Nov 23 13:44:53] [gsm.modem0] Init: net ereg
[Thu Nov 23 13:44:53] [gsm.modem0] Init: net c5greg
[Thu Nov 23 13:44:53] [gsm.modem0] GSM error in "mb_set_net_reg:603" (Called out method not supported), gsmd_method_id: "24"
[Thu Nov 23 13:44:53] [gsm.modem0] Init: clip mode
[Thu Nov 23 13:44:53] [gsm.modem0] Init: CRC mode
[Thu Nov 23 13:44:53] [gsm.modem0] Init: CNMI mode
[Thu Nov 23 13:44:53] [gsm.modem0] Scheduling init process 'CNMI mode' for "200ms"
[Thu Nov 23 13:44:53] [gsm.modem0] Modem is already in CFUN: "2"(full) state
[Thu Nov 23 13:44:53] [gsm.modem0] Init process was sheduled, last init entry executed 'CNMI mode'
[Thu Nov 23 13:44:53] [gsm.modem0] Init: net mode
[Thu Nov 23 13:44:53] [gsm.modem0] Will use "auto" service mode.
[Thu Nov 23 13:44:53] [gsm.modem0] Modem is in correct service mode "Auto"
[Thu Nov 23 13:44:53] [gsm.modem0] GSM error in "set_network_category:258" (Called out method not supported), gsmd_method_id: "114"
[Thu Nov 23 13:44:53] [gsm.modem0] Network category "m1_and_nb1" is not supported. Network category won't be changed
[Thu Nov 23 13:44:53] [gsm.modem0] Scheduling init process 'net mode' for "1500ms"
[Thu Nov 23 13:44:54] [gsm.modem0] Modem is already in CFUN: "2"(full) state
[Thu Nov 23 13:44:54] [gsm.modem0] Init process was sheduled, last init entry executed 'net mode'
[Thu Nov 23 13:44:54] [gsm.modem0] Init: default bands
[Thu Nov 23 13:44:54] [gsm.modem0] Init: SMS storage
[Thu Nov 23 13:44:54] [gsm.modem0] SMS storage: "sm"
[Thu Nov 23 13:44:54] [gsm.modem0] Modem settings init completed
[Thu Nov 23 13:44:54] [gsm.modem0] Starting connection setup
[Thu Nov 23 13:44:54] Touch: /tmp/vuci/reload_routes
[Thu Nov 23 13:44:54] [gsm.modem0] Getting APN list
[Thu Nov 23 13:44:54] [gsm.modem0] Selecting APN from mb_find_apn, preferred_apn: "2228"
[Thu Nov 23 13:44:54] [gsm.modem0] Will use "default" APN with "ipv4v6" pdptype
[Thu Nov 23 13:44:54] Registering event handler 'mob1s2a1'
[Thu Nov 23 13:44:54] [gsm.modem0] APN "IMS" is already set for PDP 2
[Thu Nov 23 13:44:54] [gsm.modem0] Modem is already in CFUN: "2"(full) state
[Thu Nov 23 13:44:54] [gsm.modem0] Preparing connection for 'mob1s2a1' interface
[Thu Nov 23 13:44:54] [gsm.modem0] Skipping first APN to not overwrite APN for 'mob1s2a1' interface
[Thu Nov 23 13:44:54] [gsm.modem0] APN "default" isn't prepared for PDP "1", preparing it
[Thu Nov 23 13:44:54] [gsm.modem0] Setting CFUN: "3"(rf)
[Thu Nov 23 13:44:55] [gsm.modem0] Setting CFUN: "2"(full)
[Thu Nov 23 13:44:58] [gsm.modem0] APN "default" was successfully prepared for PDP "1"
[Thu Nov 23 13:44:58] [gsm.modem0] Attempting to establish connection to operator with "15s" of timeout
[Thu Nov 23 13:44:58] [gsm.modem0] Network registration status received: "0"(Not registered)
[Thu Nov 23 13:44:58] [gsm.modem0] Mobile state: "Setting up connection settings"
[Thu Nov 23 13:44:58] [gsm.modem0] timeout: "15" get_timeout: "15"
[Thu Nov 23 13:44:58] [gsm.modem0] Modem is already in COPS: "1"(Auto) state
[Thu Nov 23 13:44:58] [gsm.modem0] Testing connection to "" operator
[Thu Nov 23 13:44:58] [gsm.modem0] Connected to operator "Mobitel SLTMobitel"
[Thu Nov 23 13:44:58] [gsm.modem0] Will use "auto" service mode.
[Thu Nov 23 13:44:58] [gsm.modem0] Modem is in correct service mode "Auto"
[Thu Nov 23 13:44:58] [gsm.modem0] GSM error in "set_network_category:258" (Called out method not supported), gsmd_method_id: "114"
[Thu Nov 23 13:44:58] [gsm.modem0] Network category "m1_and_nb1" is not supported. Network category won't be changed
[Thu Nov 23 13:44:58] [gsm.modem0] MBN: "ROW_Generic_3GPP", SELECTED: "1", ACTIVE: "1"
[Thu Nov 23 13:44:58] [gsm.modem0] MBN: "ROW_Generic_3GPP" saved to 'simcard' config
[Thu Nov 23 13:44:58] [gsm.modem0] Network registration status received: "2"(Searching)
[Thu Nov 23 13:44:58] [gsm.modem0] Mobile state: "Mobile connection setup is complete"
[Thu Nov 23 13:44:58] [gsm.modem0] Network registration status received: "0"(Not registered)
[Thu Nov 23 13:44:58] [gsm.modem0] Mobile state: "Mobile connection setup is complete"
[Thu Nov 23 13:44:58] [gsm.modem0] Network registration status received: "2"(Searching)
[Thu Nov 23 13:44:58] [gsm.modem0] Mobile state: "Mobile connection setup is complete"
[Thu Nov 23 13:44:58] [gsm.modem0] Network registration status received: "1"(Registered, home)
[Thu Nov 23 13:44:58] [gsm.modem0] Mobile state: "Mobile connection setup is complete"
[Thu Nov 23 13:44:58] Touch: /tmp/vuci/reload_routes
[Thu Nov 23 13:44:58] [gsm.modem0] -CFUN- Functionality: "Full"
[Thu Nov 23 13:44:58] [gsm.modem0] -COPS- Mode: "Auto", operator: "Mobitel SLTMobitel"
[Thu Nov 23 13:44:58] [gsm.modem0] -CREG- Mode: "Enabled (with location information)", status: "Registered, home", LAC: "43711", cell ID: "312145", technology: "E-UTRAN"
[Thu Nov 23 13:44:58] [gsm.modem0] -CGREG- Mode: "Enabled (with location information)", status: "Registered, home", LAC: "43711", cell ID: "312145", technology: "E-UTRAN"
[Thu Nov 23 13:44:58] [gsm.modem0] -CEREG- Mode: "Enabled (with location information)", status: "Registered, home", LAC: "43711", cell ID: "312145", technology: "E-UTRAN"
[Thu Nov 23 13:44:58] [gsm.modem0] GSM error in "mb_print_reg_status:723" (Called out method not supported), gsmd_method_id: "24"
[Thu Nov 23 13:44:59] [gsm.modem0] IP address on PDP context "1": "100.101.146.154"
[Thu Nov 23 13:44:59] [gsm.modem0] Checking IP address on PDP context "1"
[Thu Nov 23 13:44:59] [gsm.modem0] PDP type on PDP context "1" is correct
[Thu Nov 23 13:44:59] Saving APN information in "network" config for 'mob1s2a1' interface
[Thu Nov 23 13:44:59] Reloading network
9

Hello,

According to this article, the APN should be mobitel.
Additionally, I can see from the logs that SIM Switch even took place. Could you double-check that SIM switch is disabled if you are only using 1 SIM? This configuration can be found by navigating to Network → Mobile → SIM Switch.

Best regards,

10

This topic was automatically closed after 15 days. New replies are no longer allowed.