FX30s LTE - Registration state to cellular network changes every 60 minutes

#1

Hi all,

I’m testing my app on the FX30s LTE model. I’m not having any problems with my app so far, but I’m noticing that the registration state of the modem is changing consistently as follows:

> registered state = home (for one hour),
> registered state = searching/none (for half an hour),
> registered state = home (for one hour),
> registered state = searching /none (for half an hour)....and so on.

I think the problem might be associated with the AirVantage Connector api, but I’m not sure.

Has anyone seen this behavior?

I stopped my app but it didn’t make any difference. See log below:

Thanks.

Regards,

Eddie

May 10 15:28:22 | avcDaemon[3607]/avcDaemon T=main | avcServer.c PollingTimerExpiryHandler() 1966 | Polling timer expired
May 10 15:28:22 | avcDaemon[3607]/avcDaemon T=main | avcClient.c avcClient_Connect() 906 | Starting retry timer of 15 min at index 0
May 10 15:28:22 | avcDaemon[3607]/avcDaemon T=main | avcServer.c PollingTimerExpiryHandler() 1982 | A connection to server will be made in 60 minutes
May 10 15:28:22 | dcsDaemon[3622]/dcsDaemon T=main | dcsServer.c TryStartTechSession() 1517 | Device is attached, ready to start a data session
May 10 15:28:27 | dcsDaemon[3622]/le_pa_dcs T=main | pa_dcs_linux.c AddNameserversToResolvConf() 310 | Set DNS ‘10.177.0.34’ ‘10.177.0.210’
May 10 15:28:27 | dcsDaemon[3622]/le_pa_dcs T=main | pa_dcs_linux.c AddNameserversToResolvConf() 310 | Set DNS ‘fd00:976a::9’ ‘fd00:976a::10’
May 10 15:28:27 | dcsDaemon[3622]/dcsDaemon T=main | dcsServer.c SetDefaultRouteAndDns() 1318 | DNS configuration is set successfully
May 10 15:28:32 | avcDaemon[3607]/avcDaemon T=main | avcClient.c BearerEventCb() 251 | Connected 1
May 10 15:28:39 | avcDaemon[3607]/avcDaemon T=main | avcServer.c ProcessUserAgreement() 1413 | Broadcast notification to applications
May 10 15:28:40 | avcDaemon[3607]/avcDaemon T=main | osTimer.c lwm2mcore_TimerIsRunning() 213 | Timer reference is NULL
May 10 15:28:40 | avcDaemon[3607]/avcDaemon T=main | osTimer.c lwm2mcore_TimerIsRunning() 213 | Timer reference is NULL
May 10 15:28:41 | avcDaemon[3607]/avcDaemon T=main | osTimer.c lwm2mcore_TimerIsRunning() 213 | Timer reference is NULL
May 10 15:28:41 | avcDaemon[3607]/avcDaemon T=main | avcServer.c ProcessUserAgreement() 1413 | Broadcast notification to applications
May 10 15:28:42 | avcDaemon[3607]/avcDaemon T=main | osTimer.c lwm2mcore_TimerIsRunning() 213 | Timer reference is NULL
May 10 15:28:45 | avcDaemon[3607]/avcDaemon T=main | avcClient.c EventHandler() 544 | Session failure
May 10 15:28:45 | avcDaemon[3607]/avcDaemon T=main | avcClient.c EventHandler() 552 | Session failure on bootstrap server
May 10 15:28:45 | avcDaemon[3607]/avcDaemon T=main | avcServer.c ProcessUserAgreement() 1413 | Broadcast notification to applications
May 10 15:28:45 | avcDaemon[3607]/avcDaemon T=main | osTimer.c lwm2mcore_TimerStop() 179 | Timer reference is NULL
May 10 15:28:45 | dcsDaemon[3622]/dcsDaemon T=main | dcsServer.c TryStopDataSession() 1583 | Tearing down data session
May 10 15:28:46 | dcsDaemon[3622]/le_pa_dcs T=main | pa_dcs_linux.c pa_dcs_SetDefaultGateway() 568 | Default gateway or interface is empty
May 10 15:58:35 | modemDaemon[993]/modemDaemon T=main | le_mrc.c NetworkRejectIndHandler() 1170 | Network Reject Ind Handler called with RAT.8, mcc. and mnc.
May 10 15:58:35 | modemDaemon[993]/le_pa T=unknown | pa_mrc_qmi.c EventReportHandler() 1349 | Network Registration failed error code 15
May 10 15:58:37 | modemDaemon[993]/modemDaemon T=main | le_mrc.c SignalStrengthIndHandlerFunc() 686 | Signal Strength Ind Handler called with RAT.4 and ss.0
May 10 16:27:52 | modemDaemon[993]/le_pa T=main | pa_mrc_qmi.c pa_mrc_GetNetworkRegState() 2084 | called
May 10 16:28:22 | avcDaemon[3607]/avcDaemon T=main | avcServer.c PollingTimerExpiryHandler() 1966 | Polling timer expired
May 10 16:28:22 | avcDaemon[3607]/avcDaemon T=main | avcClient.c avcClient_Connect() 906 | Starting retry timer of 15 min at index 0
May 10 16:28:22 | avcDaemon[3607]/avcDaemon T=main | avcServer.c PollingTimerExpiryHandler() 1982 | A connection to server will be made in 60 minutes
May 10 16:28:22 | dcsDaemon[3622]/dcsDaemon T=main | dcsServer.c TryStartTechSession() 1517 | Device is attached, ready to start a data session
May 10 16:28:27 | dcsDaemon[3622]/le_pa_dcs T=main | pa_dcs_linux.c AddNameserversToResolvConf() 310 | Set DNS ‘10.177.0.34’ ‘10.177.0.210’
May 10 16:28:27 | dcsDaemon[3622]/le_pa_dcs T=main | pa_dcs_linux.c AddNameserversToResolvConf() 310 | Set DNS ‘fd00:976a::9’ ‘fd00:976a::10’
May 10 16:28:27 | dcsDaemon[3622]/dcsDaemon T=main | dcsServer.c SetDefaultRouteAndDns() 1318 | DNS configuration is set successfully
May 10 16:28:32 | avcDaemon[3607]/avcDaemon T=main | avcClient.c BearerEventCb() 251 | Connected 1
May 10 16:28:40 | avcDaemon[3607]/avcDaemon T=main | avcServer.c ProcessUserAgreement() 1413 | Broadcast notification to applications
May 10 16:28:41 | avcDaemon[3607]/avcDaemon T=main | osTimer.c lwm2mcore_TimerIsRunning() 213 | Timer reference is NULL
May 10 16:28:41 | avcDaemon[3607]/avcDaemon T=main | osTimer.c lwm2mcore_TimerIsRunning() 213 | Timer reference is NULL
May 10 16:28:42 | avcDaemon[3607]/avcDaemon T=main | osTimer.c lwm2mcore_TimerIsRunning() 213 | Timer reference is NULL
May 10 16:28:42 | avcDaemon[3607]/avcDaemon T=main | avcServer.c ProcessUserAgreement() 1413 | Broadcast notification to applications
May 10 16:28:42 | avcDaemon[3607]/avcDaemon T=main | osTimer.c lwm2mcore_TimerIsRunning() 213 | Timer reference is NULL
May 10 16:28:46 | avcDaemon[3607]/avcDaemon T=main | avcClient.c EventHandler() 544 | Session failure
May 10 16:28:46 | avcDaemon[3607]/avcDaemon T=main | avcClient.c EventHandler() 552 | Session failure on bootstrap server
May 10 16:28:46 | avcDaemon[3607]/avcDaemon T=main | avcServer.c ProcessUserAgreement() 1413 | Broadcast notification to applications
May 10 16:28:46 | avcDaemon[3607]/avcDaemon T=main | osTimer.c lwm2mcore_TimerStop() 179 | Timer reference is NULL
May 10 16:28:46 | dcsDaemon[3622]/dcsDaemon T=main | dcsServer.c TryStopDataSession() 1583 | Tearing down data session
May 10 16:28:47 | dcsDaemon[3622]/le_pa_dcs T=main | pa_dcs_linux.c pa_dcs_SetDefaultGateway() 568 | Default gateway or interface is empty
May 10 17:28:21 | modemDaemon[993]/le_pa T=unknown | pa_mrc_qmi.c EventReportHandler() 1349 | Network Registration failed error code 15
May 10 17:28:21 | modemDaemon[993]/modemDaemon T=main | le_mrc.c NetworkRejectIndHandler() 1170 | Network Reject Ind Handler called with RAT.8, mcc. and mnc.
May 10 17:28:22 | avcDaemon[3607]/avcDaemon T=main | avcServer.c PollingTimerExpiryHandler() 1966 | Polling timer expired
May 10 17:28:22 | avcDaemon[3607]/avcDaemon T=main | avcClient.c avcClient_Connect() 906 | Starting retry timer of 15 min at index 0
May 10 17:28:23 | avcDaemon[3607]/avcDaemon T=main | avcServer.c PollingTimerExpiryHandler() 1982 | A connection to server will be made in 60 minutes
May 10 17:28:23 | dcsDaemon[3622]/dcsDaemon T=main | dcsServer.c TryStartTechSession() 1517 | Device is attached, ready to start a data session
May 10 17:28:23 | modemDaemon[993]/swiQmi T=main | swiQmi.c swiQmi_CheckResponse() 820 | Sending QMI_WDS_START_NETWORK_INTERFACE_REQ_V01 failed: rc=0 (), resp.result=1.[0x01], resp.error=14.[0x0e]
May 10 17:28:23 | modemDaemon[993]/le_pa T=main | pa_mdc_qmi.c StartSession() 1903 | Data connection failure Call End provided 45, Code 1
May 10 17:28:23 | modemDaemon[993]/le_pa T=main | pa_mdc_qmi.c StartSession() 1914 | Data connection failure Verbose Call End provided Type 3, Verbose 1078
May 10 17:28:28 | modemDaemon[993]/swiQmi T=main | swiQmi.c swiQmi_CheckResponse() 820 | Sending QMI_WDS_START_NETWORK_INTERFACE_REQ_V01 failed: rc=0 (), resp.result=1.[0x01], resp.error=14.[0x0e]
May 10 17:28:28 | modemDaemon[993]/le_pa T=main | pa_mdc_qmi.c StartSession() 1903 | Data connection failure Call End provided 0, Code 3
May 10 17:28:28 | modemDaemon[993]/le_pa T=main | pa_mdc_qmi.c StartSession() 1914 | Data connection failure Verbose Call End provided Type 3, Verbose 1078
May 10 17:28:28 | modemDaemon[993]/modemDaemon T=main | le_mdc.c le_mdc_StartSession() 1008 | Get Connection failure 0, 3, 3, 1078
May 10 17:28:28 | modemDaemon[993]/modemDaemon T=main | le_mrc.c SignalStrengthIndHandlerFunc() 686 | Signal Strength Ind Handler called with RAT.4 and ss.-59
May 10 17:28:33 | dcsDaemon[3622]/le_pa_dcs T=main | pa_dcs_linux.c pa_dcs_SetDefaultGateway() 568 | Default gateway or interface is empty
May 10 17:43:23 | dcsDaemon[3622]/le_pa_dcs T=main | pa_dcs_linux.c pa_dcs_SetDefaultGateway() 568 | Default gateway or interface is empty
May 10 17:43:23 | avcDaemon[3607]/avcDaemon T=main | avcClient.c avcClient_Connect() 906 | Starting retry timer of 60 min at index 1
May 10 17:57:30 | modemDaemon[993]/le_pa T=main | pa_mrc_qmi.c pa_mrc_GetNetworkRegState() 2084 | called
May 10 17:57:36 | dcsDaemon[3622]/le_pa_dcs T=main | pa_dcs_linux.c AddNameserversToResolvConf() 310 | Set DNS ‘10.177.0.34’ ‘10.177.0.210’
May 10 17:57:36 | dcsDaemon[3622]/le_pa_dcs T=main | pa_dcs_linux.c AddNameserversToResolvConf() 310 | Set DNS ‘fd00:976a::9’ ‘fd00:976a::10’
May 10 17:57:36 | dcsDaemon[3622]/dcsDaemon T=main | dcsServer.c SetDefaultRouteAndDns() 1318 | DNS configuration is set successfully
May 10 17:57:41 | avcDaemon[3607]/avcDaemon T=main | avcClient.c BearerEventCb() 251 | Connected 1
May 10 17:57:48 | avcDaemon[3607]/avcDaemon T=main | avcServer.c ProcessUserAgreement() 1413 | Broadcast notification to applications
May 10 17:57:48 | avcDaemon[3607]/avcDaemon T=main | osTimer.c lwm2mcore_TimerIsRunning() 213 | Timer reference is NULL
May 10 17:57:49 | avcDaemon[3607]/avcDaemon T=main | osTimer.c lwm2mcore_TimerIsRunning() 213 | Timer reference is NULL
May 10 17:57:49 | avcDaemon[3607]/avcDaemon T=main | osTimer.c lwm2mcore_TimerIsRunning() 213 | Timer reference is NULL
May 10 17:57:49 | avcDaemon[3607]/avcDaemon T=main | avcServer.c ProcessUserAgreement() 1413 | Broadcast notification to applications
May 10 17:57:50 | avcDaemon[3607]/avcDaemon T=main | osTimer.c lwm2mcore_TimerIsRunning() 213 | Timer reference is NULL
May 10 17:57:54 | avcDaemon[3607]/avcDaemon T=main | avcClient.c EventHandler() 544 | Session failure
May 10 17:57:54 | avcDaemon[3607]/avcDaemon T=main | avcClient.c EventHandler() 552 | Session failure on bootstrap server
May 10 17:57:54 | avcDaemon[3607]/avcDaemon T=main | avcServer.c ProcessUserAgreement() 1413 | Broadcast notification to applications
May 10 17:57:54 | avcDaemon[3607]/avcDaemon T=main | osTimer.c lwm2mcore_TimerStop() 179 | Timer reference is NULL
May 10 17:57:54 | dcsDaemon[3622]/dcsDaemon T=main | dcsServer.c TryStopDataSession() 1583 | Tearing down data session
May 10 17:57:54 | dcsDaemon[3622]/le_pa_dcs T=main | pa_dcs_linux.c pa_dcs_SetDefaultGateway() 568 | Default gateway or interface is empty
May 10 18:28:23 | avcDaemon[3607]/avcDaemon T=main | avcServer.c PollingTimerExpiryHandler() 1966 | Polling timer expired
May 10 18:28:23 | avcDaemon[3607]/avcDaemon T=main | avcClient.c avcClient_Connect() 906 | Starting retry timer of 15 min at index 0
May 10 18:28:23 | avcDaemon[3607]/avcDaemon T=main | avcServer.c PollingTimerExpiryHandler() 1982 | A connection to server will be made in 60 minutes
May 10 18:28:23 | dcsDaemon[3622]/dcsDaemon T=main | dcsServer.c TryStartTechSession() 1517 | Device is attached, ready to start a data session
May 10 18:28:27 | dcsDaemon[3622]/le_pa_dcs T=main | pa_dcs_linux.c AddNameserversToResolvConf() 310 | Set DNS ‘10.177.0.34’ ‘10.177.0.210’
May 10 18:28:27 | dcsDaemon[3622]/le_pa_dcs T=main | pa_dcs_linux.c AddNameserversToResolvConf() 310 | Set DNS ‘fd00:976a::9’ ‘fd00:976a::10’
May 10 18:28:27 | dcsDaemon[3622]/dcsDaemon T=main | dcsServer.c SetDefaultRouteAndDns() 1318 | DNS configuration is set successfully
May 10 18:28:32 | avcDaemon[3607]/avcDaemon T=main | avcClient.c BearerEventCb() 251 | Connected 1
May 10 18:28:39 | avcDaemon[3607]/avcDaemon T=main | avcServer.c ProcessUserAgreement() 1413 | Broadcast notification to applications
May 10 18:28:40 | avcDaemon[3607]/avcDaemon T=main | osTimer.c lwm2mcore_TimerIsRunning() 213 | Timer reference is NULL
May 10 18:28:40 | avcDaemon[3607]/avcDaemon T=main | osTimer.c lwm2mcore_TimerIsRunning() 213 | Timer reference is NULL
May 10 18:28:40 | avcDaemon[3607]/avcDaemon T=main | osTimer.c lwm2mcore_TimerIsRunning() 213 | Timer reference is NULL
May 10 18:28:40 | avcDaemon[3607]/avcDaemon T=main | avcServer.c ProcessUserAgreement() 1413 | Broadcast notification to applications
May 10 18:28:42 | avcDaemon[3607]/avcDaemon T=main | osTimer.c lwm2mcore_TimerIsRunning() 213 | Timer reference is NULL
May 10 18:28:45 | avcDaemon[3607]/avcDaemon T=main | avcClient.c EventHandler() 544 | Session failure
May 10 18:28:45 | avcDaemon[3607]/avcDaemon T=main | avcClient.c EventHandler() 552 | Session failure on bootstrap server
May 10 18:28:45 | avcDaemon[3607]/avcDaemon T=main | avcServer.c ProcessUserAgreement() 1413 | Broadcast notification to applications
May 10 18:28:45 | avcDaemon[3607]/avcDaemon T=main | osTimer.c lwm2mcore_TimerStop() 179 | Timer reference is NULL
May 10 18:28:45 | dcsDaemon[3622]/dcsDaemon T=main | dcsServer.c TryStopDataSession() 1583 | Tearing down data session
May 10 18:28:46 | dcsDaemon[3622]/le_pa_dcs T=main | pa_dcs_linux.c pa_dcs_SetDefaultGateway() 568 | Default gateway or interface is empty
May 10 18:58:02 | modemDaemon[993]/modemDaemon T=main | le_mrc.c NetworkRejectIndHandler() 1170 | Network Reject Ind Handler called with RAT.8, mcc. and mnc.
May 10 18:58:02 | modemDaemon[993]/le_pa T=unknown | pa_mrc_qmi.c EventReportHandler() 1349 | Network Registration failed error code 15
May 10 18:58:04 | modemDaemon[993]/modemDaemon T=main | le_mrc.c SignalStrengthIndHandlerFunc() 686 | Signal Strength Ind Handler called with RAT.4 and ss.-70

#2

@eddie435,

Not sure which FX30 you are using since you say to are using the LTE version, there isn’t an LTE version other than the LPWA one, otherwise you are looking at the standard 3G version?

Re the registration state do you see it attaching and detaching from the network through AT commands? To check this I would enable the unsolicited responses as below and leave it to run, ideally with a terminal that has time stamps on it.

at+creg=1
at+cgreg=1

This will show if it is coming on and off the network, if it is then it will almost certainly not have anything to do with the connector as it will leave the signalling firmware to do its thing and select the best network.

Regards

Matt

#3

Hi Matt,

Thanks for responding.

I’m using the FX30S - WP7702

Here is the current status info:

ati
Manufacturer: Sierra Wireless, Incorporated
Model: FX30S(WP7702)
Revision: SWI9X06Y_02.16.06.00 7605a6 jenkins 2018/06/20 17:56:12
IMEI: 354723090113639
IMEI SV: 2
FSN: VU844685010210
+GCAP: +CGSM,+DS

ati8
Legato Ver: 18.06.1_03c5394b4c271c054ae58a494f160e37_modified
Yocto Ver: SWI9X06Y_02.18.05.00 2019-01-29_11:20:10
OS Ver: Linux version 3.18.44 (jenkins@CAKEL-EV-ALBLD2) (gcc version 6.2.0 (GCC) ) #2 PREEMPT Tue Jan 29 11:21:07 PST 2019
LK Ver: 1.3.0

root@fx30s:~# fwupdate query
Connecting to service …
Firmware Version: SWI9X06Y_02.16.06.00 7605a6 jenkins 2018/06/20 17:56:12
Bootloader Version: SWI9X06Y_02.16.06.00 7605a6 jenkins 2018/06/20 17:56:12
Linux Version: 3.18.44 #2 PREEMPT Tue Jan 29 11:21:07 PST 2019
FX30 Version: R9.1.1.013

root@fx30s:~# cm info
Device: FX30S(WP7702)
IMEI: 354723090113639
IMEISV: 2
FSN: VU844685010210
Firmware Version: SWI9X06Y_02.16.06.00 7605a6 jenkins 2018/06/20 17:56:12
Bootloader Version: SWI9X06Y_02.16.06.00 7605a6 jenkins 2018/06/20 17:56:12
MCU Version: 002.009
PRI Part Number (PN): 9907957
PRI Revision: 001.000
Carrier PRI Name: GENERIC
Carrier PRI Revision: 001.028_002
SKU: 1103882
Last Reset Cause: Reset, User Requested
Resets Count: Expected: 25 Unexpected: 1

root@fx30s:~# cm radio
Power: ON
Current Network Operator: T-Mobile
Current RAT: GSM network (LE_MRC_RAT_GSM)
Status: Registered, home network (LE_MRC_REG_HOME)
Signal: Strong signal strength (4)
PS: Packet Switched Registered, home network (LE_MRC_REG_HOME)

root@fx30s:~# cm data
Index: 1
APN: fast.t-mobile.com
PDP Type: IPV4V6
Connected: yes
Interface: rmnet_data0
Family[IPv4]: inet
IP[IPv4]: 22.101.38.235
Gateway[IPv4]: 22.101.38.236
Dns1[IPv4]: 10.177.0.34
Dns2[IPv4]: 10.177.0.210
Family[IPv6]: inet6
IP[IPv6]: 2607:fb90:1b7f:f4fe:4c83:9fe0:ef2c:ad29
Gateway[IPv6]: 2607:fb90:1b7f:f4fe:c162:5fe5:81bd:f1f
Dns1[IPv6]: fd00:976a::9
Dns2[IPv6]: fd00:976a::clock10:

The modem just lost connection to the cellular network. This happens consistently where it’s on for 60 minutes, then off for 30. Here’s the log info:

root@fx30s:~# cm data
Index: 1
APN: fast.t-mobile.com
PDP Type: IPV4V6
Connected: no

root@fx30s:~# cm radio
Power: ON
Current Network Operator: T-Mobile
Current RAT: LTE network (LE_MRC_RAT_LTE)
Status: Not registered but currently searching for a new operator (LE_MRC_REG_SEARCHING)
Signal: Very strong signal strength (5)
PS: Packet Switched Not registered (LE_MRC_REG_NONE)

AT Terminal unsolicited responses:

+CREG: 3
+CGREG: 3

I’m not sure, but could this be a bug in the firmware? Currently I’m testing with a T-Mobile account. I will try with a AT&T account to see if there is any difference.

Thanks!

Regards,

Eddie