AirVantage seems to have a bug

EvetsMostel · October 12, 2018, 9:46pm

If you turn on airVantage either via AT commands or through the Legato system, when the device first boots up, it starts creating profiles with empty APNs until it maxes out the profile system and no more profiles can be created.
I’ve seen this before, but it definitely happens when the system can’t make a connection. This all happens before Legato wakes up, so I suspect it’s in the modem code somewhere.
I’m working with a WP7601 Verizon modem.
Also, it doesn’t appear that the modem code creates the profiles needed to connect. For example, if I clear all the profiles, then reboot, none of the required APN’s show up in profile one and two. Profile 3 is the normal data connection for the user, but it won’t connect if the first two aren’t there or filled in properly. This was working in R7 but R8 and R9 seemed to have gone backwards.
I currently have a working connection, with the appropriate APN’s in the right place. After a bit, This is what I get in the logs:
avcFs.c ReadFs() 41 | failed to open /avc/sw/instanceId: LE_NOT_FOUND
Oct 12 21:40:40 | avcDaemon[685]/avcDaemon T=main | avcAppUpdate.c GetSwUpdateInstanceId() 1356 | SW update instance id not found. Returning negative instance id.
Oct 12 21:40:40 | avcDaemon[685]/avcDaemon T=main | avcFs.c ReadFs() 41 | failed to open /avc/sw/internalState: LE_NOT_FOUND
Oct 12 21:40:40 | avcDaemon[685]/avcDaemon T=main | avcAppUpdate.c GetSwUpdateInternalState() 1400 | SW update internal state not found. Returning ‘INVALID’ state
Oct 12 21:40:40 | avcDaemon[685]/avcDaemon T=main | avcFs.c ReadFs() 41 | failed to open /avc/sw/instanceId: LE_NOT_FOUND
Oct 12 21:40:40 | avcDaemon[685]/avcDaemon T=main | avcAppUpdate.c GetSwUpdateInstanceId() 1356 | SW update instance id not found. Returning negative instance id.
Oct 12 21:40:40 | avcDaemon[685]/avcDaemon T=main | avcFs.c ReadFs() 41 | failed to open /avc/sw/internalState: LE_NOT_FOUND
Oct 12 21:40:40 | avcDaemon[685]/avcDaemon T=main | avcAppUpdate.c GetSwUpdateInternalState() 1400 | SW update internal state not found. Returning ‘INVALID’ state
Oct 12 21:40:40 | avcDaemon[685]/avcDaemon T=main | avcFs.c ReadFs() 41 | failed to open /avc/sw/instanceId: LE_NOT_FOUND
Oct 12 21:40:40 | avcDaemon[685]/avcDaemon T=main | avcAppUpdate.c GetSwUpdateInstanceId() 1356 | SW update instance id not found. Returning negative instance id.
Oct 12 21:40:40 | avcDaemon[685]/avcDaemon T=main | avcFs.c ReadFs() 41 | failed to open /avc/sw/internalState: LE_NOT_FOUND
Oct 12 21:40:40 | avcDaemon[685]/avcDaemon T=main | avcAppUpdate.c GetSwUpdateInternalState() 1400 | SW update internal state not found. Returning ‘INVALID’ state
Oct 12 21:40:40 | modemDaemon[690]/swiQmi T=main | swiQmi.c swiQmi_CheckResponse() 813 | Sending QMI_WDS_GET_PROFILE_SETTINGS_REQ_V01 failed: rc=0 (), resp.result=1.[0x01], resp.error=81.[0x51]
Oct 12 21:40:40 | modemDaemon[690]/swiQmi T=main | swiQmi.c swiQmi_CheckResponse() 813 | Sending QMI_WDS_GET_PROFILE_SETTINGS_REQ_V01 failed: rc=0 (), resp.result=1.[0x01], resp.error=81.[0x51]
Oct 12 21:40:40 | modemDaemon[690]/swiQmi T=main | swiQmi.c swiQmi_CheckResponse() 813 | Sending QMI_WDS_GET_PROFILE_SETTINGS_REQ_V01 failed: rc=0 (), resp.result=1.[0x01], resp.error=81.[0x51]
Oct 12 21:40:40 | modemDaemon[690]/swiQmi T=main | swiQmi.c swiQmi_CheckResponse() 813 | Sending QMI_WDS_GET_PROFILE_SETTINGS_REQ_V01 failed: rc=0 (), resp.result=1.[0x01], resp.error=81.[0x51]
Oct 12 21:40:40 | modemDaemon[690]/swiQmi T=main | swiQmi.c swiQmi_CheckResponse() 813 | Sending QMI_WDS_GET_PROFILE_SETTINGS_REQ_V01 failed: rc=0 (), resp.result=1.[0x01], resp.error=81.[0x51]
Oct 12 21:40:40 | modemDaemon[690]/swiQmi T=main | swiQmi.c swiQmi_CheckResponse() 813 | Sending QMI_WDS_GET_PROFILE_SETTINGS_REQ_V01 failed: rc=0 (), resp.result=1.[0x01], resp.error=81.[0x51]
Oct 12 21:40:40 | modemDaemon[690]/swiQmi T=main | swiQmi.c swiQmi_CheckResponse() 813 | Sending QMI_WDS_GET_PROFILE_SETTINGS_REQ_V01 failed: rc=0 (), resp.result=1.[0x01], resp.error=81.[0x51]
Oct 12 21:40:40 | modemDaemon[690]/swiQmi T=main | swiQmi.c swiQmi_CheckResponse() 813 | Sending QMI_WDS_GET_PROFILE_SETTINGS_REQ_V01 failed: rc=0 (), resp.result=1.[0x01], resp.error=81.[0x51]
Oct 12 21:40:40 | modemDaemon[690]/swiQmi T=main | swiQmi.c swiQmi_CheckResponse() 813 | Sending QMI_WDS_GET_PROFILE_SETTINGS_REQ_V01 failed: rc=0 (), resp.result=1.[0x01], resp.error=81.[0x51]
Oct 12 21:40:40 | modemDaemon[690]/swiQmi T=main | swiQmi.c swiQmi_CheckResponse() 813 | Sending QMI_WDS_GET_PROFILE_SETTINGS_REQ_V01 failed: rc=0 (), resp.result=1.[0x01], resp.error=81.[0x51]
Oct 12 21:40:40 | modemDaemon[690]/swiQmi T=main | swiQmi.c swiQmi_CheckResponse() 813 | Sending QMI_WDS_SWI_CREATE_PROFILE_REQ_V01 failed: rc=0 (), resp.result=1.[0x01], resp.error=48.[0x30]
Oct 12 21:40:40 | modemDaemon[690]/le_pa T=main | pa_mdc_qmi.c pa_mdc_InitializeProfile() 2734 | Error creating empty profile 25
Oct 12 21:40:40 | modemDaemon[690]/swiQmi T=main | swiQmi.c swiQmi_CheckResponse() 813 | Sending QMI_WDS_GET_PROFILE_SETTINGS_REQ_V01 failed: rc=0 (), resp.result=1.[0x01], resp.error=81.[0x51]
Oct 12 21:40:40 | modemDaemon[690]/swiQmi T=main | swiQmi.c swiQmi_CheckResponse() 813 | Sending QMI_WDS_SWI_CREATE_PROFILE_REQ_V01 failed: rc=0 (), resp.result=1.[0x01], resp.error=48.[0x30]
Oct 12 21:40:40 | modemDaemon[690]/le_pa T=main | pa_mdc_qmi.c pa_mdc_InitializeProfile() 2734 | Error creating empty profile 25
Oct 12 21:40:40 | modemDaemon[690]/swiQmi T=main | swiQmi.c swiQmi_CheckResponse() 813 | Sending QMI_WDS_GET_PROFILE_SETTINGS_REQ_V01 failed: rc=0 (), resp.result=1.[0x01], resp.error=81.[0x51]
Oct 12 21:40:40 | modemDaemon[690]/swiQmi T=main | swiQmi.c swiQmi_CheckResponse() 813 | Sending QMI_WDS_SWI_CREATE_PROFILE_REQ_V01 failed: rc=0 (), resp.result=1.[0x01], resp.error=48.[0x30]
Oct 12 21:40:40 | modemDaemon[690]/le_pa T=main | pa_mdc_qmi.c pa_mdc_InitializeProfile() 2734 | Error creating empty profile 25
Oct 12 21:40:41 | modemDaemon[690]/le_pa T=main | pa_mrc_qmi.c pa_mrc_GetNetworkRegState() 2062 | called
Oct 12 21:40:41 | modemDaemon[690]/le_pa T=main | pa_mrc_qmi.c pa_mrc_GetServingCellLocAreaCode() 5452 | Main cell information is void!
Oct 12 21:40:41 | modemDaemon[690]/modemDaemon T=main | le_mrc.c le_mrc_GetServingCellLocAreaCode() 4244 | Cannot retrieve the serving cell Identifier!

oabid · December 13, 2018, 2:12pm

Hi,
Which Legato version are you running?
Thank’s

EvetsMostel · December 13, 2018, 2:50pm

I was seeing this prior to 10.1. Haven’t seen it since.

oabid · December 20, 2018, 1:57pm

This has been fixed in Legato 18.06 release. Sync job in AirVantage was causing a flood on APNs.

Thank’s EvetsMostel

EvetsMostel · December 20, 2018, 2:22pm

@oabid,
Thank you for confirming this.

Evets

Topic		Replies	Views
AirVantage service failing to connect Legato Application Framework	12	1485	August 9, 2018
WP76xx device cannot connect to AirVantage - cannot Sync Legato Application Framework	5	661	November 30, 2019
Airvantage stops working Legato Application Framework	7	697	June 25, 2015
Problems when running le_avc interface, WP7702 will not connect to the airVantage server	13	363	March 31, 2021
Air Vantage - Registration Expired (WP8548) Legato Application Framework	9	1752	October 24, 2018

AirVantage seems to have a bug

Related Topics