Repeated Registration Updates with No LWM2M or HTTPs Data

IoT Modules
1

We are having an issue that has been happening intermittently to deployed units. The devices which were pushing sensor data via LWM2M close their data session and stop sending data and then never send LWM2M unit they are remotely rebooted. While in this state the constantly show registration updates in AirVantage.

image
image1004×679 43.4 KB

It seems like this issue occurs when device loses it connection to the network and then tries to reestablish it. Until now this has been happening on deployed units only but over this past weekend it happened to a demonstration unit that we could get the logs from. Unfortunately the logs don’t go back far enough to the moment when the unit went offline but I did notice somethings I haven’t seen before, see below.

I was able to ascertain that the all the apps seemed to be working, it’s just that no data was able to be transmitted LWM2M or HTTPS.

Thanks,

Dec  6 12:23:25 swi-mdm9x15 user.err Legato: =ERR= | avcDaemon[25098] | Dec 06 12:23:25 
Dec  6 12:23:25 swi-mdm9x15 user.err Legato: =ERR= | avcDaemon[25098] | ALRT 
Dec  6 12:23:25 swi-mdm9x15 user.err Legato: =ERR= | avcDaemon[25098] | No security context for epoch: 0

and

Dec  6 20:51:06 swi-mdm9x15 user.warn Legato: -WRN- | _UNKNOWN_[639]/le_pa T=unknown | pa_mrc_qmi.c ConvertPSState() 770 | PS state unknown
Dec  6 20:51:06 swi-mdm9x15 user.warn Legato: -WRN- | modemDaemon[985]/le_pa T=unknown | pa_mrc_qmi.c ConvertPSState() 770 | PS state unknown
Dec  6 20:51:06 swi-mdm9x15 user.err Legato: =ERR= | dcsDaemon[975]/dcsDaemon T=main | dcsServer.c PacketSwitchHandler() 2278 | New PS state unknown PS state 5
Dec  6 20:51:06 swi-mdm9x15 user.info Legato:  INFO | dcsDaemon[975]/dcsCellular T=main | dcsCellular.c DcsCellularPacketSwitchHandler() 673 | Packet switch state: previous 4, new 5
Dec  6 20:51:06 swi-mdm9x15 user.info Legato:  INFO | dcsDaemon[975]/dcs T=main | dcs_db.c le_dcs_EventNotifierTechStateTransition() 341 | Notify all channels of technology 2 of system state transition to down
Dec  6 20:51:06 swi-mdm9x15 user.warn Legato: -WRN- | modemDaemon[985]/le_pa T=main | pa_sim_qmi.c MapSimState() 880 | More than one application 2
Dec  6 20:51:06 swi-mdm9x15 user.info Legato:  INFO | modemDaemon[985]/le_pa T=main | pa_sim_qmi.c InternalSimToolkitHandler() 1449 | QMI_UIM_REFRESH_COMPLETE_REQ_V01: Err 0 resp 0
Dec  6 20:51:07 swi-mdm9x15 user.warn Legato: -WRN- | _UNKNOWN_[639]/le_pa T=ModemPAThread | pa_sim_qmi.c MapSimState() 880 | More than one application 2
Dec  6 20:51:07 swi-mdm9x15 user.warn Legato: -WRN- | modemDaemon[985]/le_pa T=main | pa_sim_qmi.c MapSimState() 880 | More than one application 2
Dec  6 20:51:07 swi-mdm9x15 user.warn Legato: -WRN- | _UNKNOWN_[639]/le_pa T=ModemPAThread | pa_sim_qmi.c MapSimState() 880 | More than one application 2
Dec  6 20:51:07 swi-mdm9x15 user.warn Legato: -WRN- | modemDaemon[985]/le_pa T=main | pa_sim_qmi.c MapSimState() 880 | More than one application 2
Dec  6 20:51:07 swi-mdm9x15 user.info Legato:  INFO | modemDaemon[985]/modemDaemon T=main | le_sim.c SimToolkitHandler() 942 | Update SIM Card information after a refresh
Dec  6 20:51:07 swi-mdm9x15 user.warn Legato: -WRN- | modemDaemon[985]/le_pa T=main | pa_sim_qmi.c RetrieveSimApplicationType() 1949 | More than one application 2)
Dec  6 20:51:07 swi-mdm9x15 user.err Legato: =ERR= | modemDaemon[985]/le_pa_default T=main | pa_sim_default.c pa_sim_GetCardEID() 121 | Unsupported function called
Dec  6 20:51:08 swi-mdm9x15 user.info Legato:  INFO | dcsDaemon[975]/dcsCellular T=main | dcsCellular.c DcsCellularPacketSwitchHandler() 673 | Packet switch state: previous 5, new 0
Dec  6 20:51:08 swi-mdm9x15 user.warn Legato: -WRN- | modemDaemon[985]/le_pa T=main | pa_sim_qmi.c MapSimState() 880 | More than one application 2
Dec  6 20:51:08 swi-mdm9x15 user.warn Legato: -WRN- | _UNKNOWN_[639]/le_pa T=unknown | pa_mrc_qmi.c ConvertPSState() 770 | PS state unknown
Dec  6 20:51:08 swi-mdm9x15 user.warn Legato: -WRN- | modemDaemon[985]/le_pa T=unknown | pa_mrc_qmi.c ConvertPSState() 770 | PS state unknown
Dec  6 20:51:08 swi-mdm9x15 user.err Legato: =ERR= | dcsDaemon[975]/dcsDaemon T=main | dcsServer.c PacketSwitchHandler() 2278 | New PS state unknown PS state 5
Dec  6 20:51:08 swi-mdm9x15 user.info Legato:  INFO | dcsDaemon[975]/dcsCellular T=main | dcsCellular.c DcsCellularPacketSwitchHandler() 673 | Packet switch state: previous 0, new 5
Dec  6 20:51:08 swi-mdm9x15 user.warn Legato: -WRN- | modemDaemon[985]/le_pa T=main | pa_sim_qmi.c MapSimState() 880 | More than one application 2
Dec  6 20:51:08 swi-mdm9x15 user.debug kernel: [331699.218731] NMEA read driver miss interrupt, abandon current buff
Dec  6 20:51:12 swi-mdm9x15 user.warn Legato: -WRN- | _UNKNOWN_[639]/le_pa T=unknown | pa_mrc_qmi.c ConvertPSState() 770 | PS state unknown
Dec  6 20:51:12 swi-mdm9x15 user.warn Legato: -WRN- | modemDaemon[985]/le_pa T=unknown | pa_mrc_qmi.c ConvertPSState() 770 | PS state unknown
2

Hi @dbeckwith,
Maybe you can provide a bit more details to help understand the situation

  • Which product are you working ?
  • Which the version are you working ?
    We recommend customer to upgrade the latest FW to get all supported features and fixed bug.
3

@Vianney

I thought I added those details, sorry.

This occurring on a WP8548 module on custom hardware built off of Mangoh Red. We are running Legato 19.02.0 and firmware R16.1.

4

Hi @dbeckwith,
I am not sure about the use-case causing your problem. I tried the scenario and get the result below. Pleaase help to check and correct if it’s not yours

  1. Start a push data application in ALMS
  2. The data is pushed
  3. Stop data session by command WDSS=1,0
  4. Disconnect cellular network by at+cfun=4. Wait for a min
  5. Reconnect cellular network. Check timeline in ALMS

image
image819×624 38.8 KB

The data session is automatically recovered as the screenshot
If you still get the issue please collect the reveal log to clarify it
Thanks

5

@Vianney

I’m not quite sure what you are asking.

This timeline seems similar to our situation but the trouble we are having is we no longer get sensor data via LWM2M or HTTPs.

We are interfacing to the modem via our internal apps and the le_avdata API. The logs I provided are all that I managed to recover for this unit.

Thanks,
Darren

6

Hi @dbeckwith,

The devices which were pushing sensor data via LWM2M close their data session and stop sending data and then never send LWM2M unit they are remotely rebooted

I am not sure which result you expected here. When the module closes the data session and stops sending data the module will not send data anymore. If you want the module to re-push the data you have to create a new session for it
I was not clear the steps which caused the issue on the module. So I asked the actual scenario (which steps/commands you made on the module/ALMS) for it. As my previous comment, I tried to reproduce your issue on mine but I didn’t get any error (you can review the above scenario to compare with yours). Please list the details to reproduce the issue and the application you were using for the module then I can try on mine to see what is the problem.

7

@Vianney

Sorry I wasn’t clear in my original post. We have built in retry logic in our apps that automatically establish a session after it is lost.

What I’m trying to understand why is the device providing registration events in AirVantage if it doesn’t have an LWM2M or HTTPs communication from our apps? We are running the avcControler app in our product with heartbeats set to every 15 mins. I guess a better question is what are registration and registration update events and why are these events appearing in the timeline? The modem seems to have a link to AV because we are able to remotely reboot the device.

8

Hi @dbeckwith,

Which apps are involving in? There might have a conflict between apps. Please share more details of the apps and the logs which capture the problem (They should be more than 15 mins at least). The log you shared is very short. It is hard to see what happened.
Regards