Hello all,
I’m having an issue with the WP7607 running legato 18.10.3.
When I run the “cm data connect” command I get the following error, saying that the etc/resolv.conf file is busy:
mv: can't remove '/etc/resolv.conf': Device or resource busy
I ran fuser -m /etc/resolv.conf and it tells that the file is used by the “supervisor” process.
No idea how to fix this. Any suggestions?
Do you actually have a problem connecting though? There is an issue logged in the internal Legato bug tracker for this, but it doesn’t seem to indicate that there should be any connectivity problem.
Hello
Yes I do have connectivity problems in terms of failed DNS queries.
The DNS stored in the resolv.conf are no longer valid and the “cm data connect” can’t update them.
Connectivity in and of itself does work (e.g. I can ping a publicly reachable IP address) but without DNS resolutions the system is unusable.
Do you have an idea when this issue was introduced? Which is the latest legato version that comes without this bug?
Thanks
@dfrey ans @parmeggianig
I am experiencing the same issue on Legato 18.09.0. I can ping 8.8.8.8 but I can’t establish a data connection for long periods of time (hours in a lot of cases). The suddenly it will just work. Here is an excerpt from my logs.
May 27 15:21:52 swi-mdm9x15 user.err Legato: =ERR= | avcDaemon[1184]/avcDaemon T=main | avcClient.c EventHandler() 544 | Session failure May 27 15:21:52 swi-mdm9x15 user.err Legato: =ERR= | avcDaemon[1184]/avcDaemon T=main | avcClient.c EventHandler() 552 | Session failure on bootstrap server May 27 15:21:52 swi-mdm9x15 user.info Legato: INFO | avcDaemon[1184]/avcDaemon T=main | avcServer.c ProcessUserAgreement() 1472 | Broadcast notification to applications May 27 15:21:52 swi-mdm9x15 user.err Legato: =ERR= | avcDaemon[1184]/avcDaemon T=main | osTimer.c lwm2mcore_TimerStop() 173 | Unable to stop the timer May 27 15:21:52 swi-mdm9x15 user.err Legato: =ERR= | avcControl[1007]/avcControl T=main | avcControl.c StatusHandler() 165 | Air Vantage agent reported unexpected update status: 16 May 27 15:21:52 swi-mdm9x15 user.info Legato: INFO | errorService[1033]/errorHandler T=main | errorHandler.c avSessionStateHandler() 66 | AV Session State Handler 1 May 27 15:21:52 swi-mdm9x15 user.warn Legato: -WRN- | errorService[1033]/errorHandler T=main | errorHandler.c avSessionStateHandler() 77 | Lost AV session May 27 15:21:52 swi-mdm9x15 user.info Legato: INFO | photoService[1072]/photoManager T=main | photoManager.c avSessionStateHandler() 662 | AV Session State Handler 1 May 27 15:21:52 swi-mdm9x15 user.warn Legato: -WRN- | photoService[1072]/photoManager T=main | photoManager.c avSessionStateHandler() 673 | Lost AV session May 27 15:21:52 swi-mdm9x15 user.info Legato: INFO | sensorToCloud[1102]/avPublisher T=main | avPublisher.c avSessionStateHandler() 1080 | AV Session State Handler 1 May 27 15:21:52 swi-mdm9x15 user.warn Legato: -WRN- | sensorToCloud[1102]/avPublisher T=main | avPublisher.c avSessionStateHandler() 1097 | Lost AV session May 27 15:21:52 swi-mdm9x15 user.warn Legato: -WRN- | sensorToCloud[1102]/avPublisher T=main | avPublisher.c avSessionStateHandler() 1098 | Starting session retry timer May 27 15:21:52 swi-mdm9x15 user.info Legato: INFO | avcControl[1007]/avcControl T=main | avcControl.c StatusHandler() 167 | Air Vantage agent reported update status: SESSION_STOPPED May 27 15:22:00 swi-mdm9x15 user.err Legato: =ERR= | modemDaemon[1059]/modemDaemon T=main | le_sms.c CloseSessionEventHandler() 2181 | SessionRef (0xb6fcfbbc) has been closed May 27 15:22:01 swi-mdm9x15 user.err Legato: =ERR= | modemDaemon[1059]/modemDaemon T=main | le_sms.c CloseSessionEventHandler() 2181 | SessionRef (0xb6fcf894) has been closed May 27 15:22:19 swi-mdm9x15 user.err Legato: =ERR= | modemDaemon[1059]/modemDaemon T=main | le_sms.c CloseSessionEventHandler() 2181 | SessionRef (0xb6fcfbbc) has been closed May 27 15:22:20 swi-mdm9x15 user.err Legato: =ERR= | modemDaemon[1059]/modemDaemon T=main | le_sms.c CloseSessionEventHandler() 2181 | SessionRef (0xb6fcf894) has been closed May 27 15:25:46 swi-mdm9x15 user.info Legato: INFO | sensorToCloud[1102]/avPublisher T=main | avPublisher.c sessionTimerHandler() 1115 | No AV session timeout handler May 27 15:25:46 swi-mdm9x15 user.info Legato: INFO | sensorToCloud[1102]/avPublisher T=main | avPublisher.c sessionTimerHandler() 1118 | Releasing AV session May 27 15:25:46 swi-mdm9x15 user.info Legato: INFO | sensorToCloud[1102]/avPublisher T=main | avPublisher.c sessionTimerHandler() 1123 | Attempting to Re-requesting AV session May 27 15:25:46 swi-mdm9x15 user.info Legato: INFO | avcDaemon[1184]/avcDaemon T=main | avcClient.c BearerEventCb() 251 | Connected 1 May 27 15:25:46 swi-mdm9x15 user.info Legato: INFO | sensorToCloud[1102]/avPublisher T=main | avPublisher.c sessionTimerHandler() 1129 | AirVantage Connection Controller started. May 27 15:25:47 swi-mdm9x15 user.info Legato: INFO | sensorToCloud[1102]/avPublisher T=main | avPublisher.c updateSensors() 515 | Updating sensorConfig... May 27 15:25:47 swi-mdm9x15 user.info Legato: INFO | sensorToCloud[1102]/avPublisher T=main | avPublisher.c isEnabled() 510 | /digitalService/sensorConfig/gps_showOnGrid:-1 May 27 15:25:47 swi-mdm9x15 user.info Legato: INFO | sensorToCloud[1102]/avPublisher T=main | avPublisher.c isEnabled() 510 | /digitalService/sensorConfig/pressure1_showOnGrid:-1 May 27 15:25:47 swi-mdm9x15 user.info Legato: INFO | sensorToCloud[1102]/avPublisher T=main | avPublisher.c isEnabled() 510 | /digitalService/sensorConfig/humidity1_showOnGrid:-1 May 27 15:25:47 swi-mdm9x15 user.info Legato: INFO | sensorToCloud[1102]/avPublisher T=main | avPublisher.c isEnabled() 510 | /digitalService/sensorConfig/digital1_showOnGrid:-21 May 27 15:25:47 swi-mdm9x15 user.info Legato: INFO | sensorToCloud[1102]/avPublisher T=main | avPublisher.c isEnabled() 510 | /digitalService/sensorConfig/digital2_showOnGrid:-21 May 27 15:25:47 swi-mdm9x15 user.info Legato: INFO | sensorToCloud[1102]/avPublisher T=main | avPublisher.c isEnabled() 510 | /digitalService/sensorConfig/digital3_showOnGrid:-21 May 27 15:25:47 swi-mdm9x15 user.info Legato: INFO | sensorToCloud[1102]/avPublisher T=main | avPublisher.c isEnabled() 510 | /digitalService/sensorConfig/digital4_showOnGrid:-21 May 27 15:25:47 swi-mdm9x15 user.info Legato: INFO | sensorToCloud[1102]/avPublisher T=main | avPublisher.c isEnabled() 510 | /digitalService/sensorConfig/bilge1_showOnGrid:-21 May 27 15:25:47 swi-mdm9x15 user.info Legato: INFO | sensorToCloud[1102]/avPublisher T=main | avPublisher.c isEnabled() 510 | /digitalService/sensorConfig/shorepwr1_showOnGrid:-21 May 27 15:25:47 swi-mdm9x15 user.info Legato: INFO | sensorToCloud[1102]/avPublisher T=main | avPublisher.c isEnabled() 510 | /digitalService/sensorConfig/pir1_showOnGrid:-21 May 27 15:25:47 swi-mdm9x15 user.info Legato: INFO | sensorToCloud[1102]/avPublisher T=main | avPublisher.c isEnabled() 510 | /digitalService/sensorConfig/pushbtn1_showOnGrid:-21 May 27 15:25:47 swi-mdm9x15 user.info Legato: INFO | sensorToCloud[1102]/avPublisher T=main | avPublisher.c isEnabled() 510 | /digitalService/sensorConfig/temp1_showOnGrid:-1 May 27 15:25:47 swi-mdm9x15 user.info Legato: INFO | sensorToCloud[1102]/avPublisher T=main | avPublisher.c isEnabled() 510 | /digitalService/sensorConfig/analog1_showOnGrid:-1 May 27 15:25:47 swi-mdm9x15 user.info Legato: INFO | sensorToCloud[1102]/avPublisher T=main | avPublisher.c isEnabled() 510 | /digitalService/sensorConfig/analog2_showOnGrid:-1 May 27 15:25:47 swi-mdm9x15 user.info Legato: INFO | sensorToCloud[1102]/avPublisher T=main | avPublisher.c isEnabled() 510 | /digitalService/sensorConfig/analog3_showOnGrid:-1 May 27 15:25:47 swi-mdm9x15 user.info Legato: INFO | sensorToCloud[1102]/avPublisher T=main | avPublisher.c isEnabled() 510 | /digitalService/sensorConfig/analog4_showOnGrid:-1 May 27 15:25:47 swi-mdm9x15 user.info Legato: INFO | sensorToCloud[1102]/avPublisher T=main | avPublisher.c isEnabled() 510 | /digitalService/sensorConfig/accelerometer_showOnGrid:-1 May 27 15:25:47 swi-mdm9x15 user.info Legato: INFO | sensorToCloud[1102]/avPublisher T=main | avPublisher.c isEnabled() 510 | /digitalService/sensorConfig/cellstrength_showOnGrid:-1 May 27 15:25:47 swi-mdm9x15 user.info Legato: INFO | sensorToCloud[1102]/avPublisher T=main | avPublisher.c updateSensors() 520 | Updating sensorConfig complete May 27 15:25:50 swi-mdm9x15 user.info Legato: INFO | avcDaemon[1184]/avcDaemon T=main | avcServer.c ProcessUserAgreement() 1472 | Broadcast notification to applications May 27 15:25:50 swi-mdm9x15 user.info Legato: INFO | avcControl[1007]/avcControl T=main | avcControl.c StatusHandler() 167 | Air Vantage agent reported update status: AUTHENTICATION_STARTED May 27 15:25:52 swi-mdm9x15 user.info Legato: INFO | avcDaemon[1184]/avcDaemon T=main | avcServer.c ProcessUserAgreement() 1472 | Broadcast notification to applications May 27 15:25:52 swi-mdm9x15 user.err Legato: =ERR= | avcControl[1007]/avcControl T=main | avcControl.c StatusHandler() 165 | Air Vantage agent reported unexpected update status: 14 May 27 15:25:57 swi-mdm9x15 user.err Legato: =ERR= | secStore[1096]/swiQmi T=main | swiQmi.c swiQmi_CheckResponse() 795 | Sending QMI_SWI_SFS_OPEN_REQ_V01 failed: rc=0 (), resp.result=1.[0x01], resp.error=48.[0x30] May 27 15:25:57 swi-mdm9x15 user.err Legato: =ERR= | secStore[1096]/swiQmi T=main | swiQmi.c swiQmi_CheckResponse() 795 | Sending QMI_SWI_SFS_OPEN_REQ_V01 failed: rc=0 (), resp.result=1.[0x01], resp.error=48.[0x30] May 27 15:25:57 swi-mdm9x15 user.err Legato: =ERR= | secStore[1096]/swiQmi T=main | swiQmi.c swiQmi_CheckResponse() 795 | Sending QMI_SWI_SFS_OPEN_REQ_V01 failed: rc=0 (), resp.result=1.[0x01], resp.error=48.[0x30] May 27 15:25:57 swi-mdm9x15 user.err Legato: =ERR= | secStore[1096]/swiQmi T=main | swiQmi.c swiQmi_CheckResponse() 795 | Sending QMI_SWI_SFS_OPEN_REQ_V01 failed: rc=0 (), resp.result=1.[0x01], resp.error=48.[0x30] May 27 15:25:57 swi-mdm9x15 user.err Legato: =ERR= | secStore[1096]/swiQmi T=main | swiQmi.c swiQmi_CheckResponse() 795 | Sending QMI_SWI_SFS_OPEN_REQ_V01 failed: rc=0 (), resp.result=1.[0x01], resp.error=48.[0x30] May 27 15:25:57 swi-mdm9x15 user.err Legato: =ERR= | secStore[1096]/swiQmi T=main | swiQmi.c swiQmi_CheckResponse() 795 | Sending QMI_SWI_SFS_OPEN_REQ_V01 failed: rc=0 (), resp.result=1.[0x01], resp.error=48.[0x30] May 27 15:25:57 swi-mdm9x15 user.err Legato: =ERR= | secStore[1096]/swiQmi T=main | swiQmi.c swiQmi_CheckResponse() 795 | Sending QMI_SWI_SFS_OPEN_REQ_V01 failed: rc=0 (), resp.result=1.[0x01], resp.error=48.[0x30] May 27 15:25:57 swi-mdm9x15 user.info Legato: INFO | avcDaemon[1184]/avcDaemon T=main | avcServer.c ProcessUserAgreement() 1472 | Broadcast notification to applications May 27 15:25:57 swi-mdm9x15 user.info Legato: INFO | avcControl[1007]/avcControl T=main | avcControl.c StatusHandler() 167 | Air Vantage agent reported update status: AUTHENTICATION_STARTED May 27 15:25:58 swi-mdm9x15 user.err Legato: =ERR= | secStore[1096]/swiQmi T=main | swiQmi.c swiQmi_CheckResponse() 795 | Sending QMI_SWI_SFS_OPEN_REQ_V01 failed: rc=0 (), resp.result=1.[0x01], resp.error=48.[0x30] May 27 15:25:58 swi-mdm9x15 user.err Legato: =ERR= | secStore[1096]/swiQmi T=main | swiQmi.c swiQmi_CheckResponse() 795 | Sending QMI_SWI_SFS_OPEN_REQ_V01 failed: rc=0 (), resp.result=1.[0x01], resp.error=48.[0x30] May 27 15:26:00 swi-mdm9x15 user.err Legato: =ERR= | avcDaemon[1184]/avcDaemon T=main | avcFs.c ReadFs() 41 | failed to open /avc/packageDownloader/packageUri: LE_NOT_FOUND May 27 15:26:00 swi-mdm9x15 user.err Legato: =ERR= | avcDaemon[1184]/avcDaemon T=main | packageDownloader.c packageDownloader_GetResumeInfo() 323 | Failed to read /avc/packageDownloader/packageUri: LE_NOT_FOUND May 27 15:26:00 swi-mdm9x15 user.info Legato: INFO | avcDaemon[1184]/avcDaemon T=main | avcAppUpdate.c NotifyObj9List() 691 | Found 9 object 9 instances May 27 15:26:00 swi-mdm9x15 user.info Legato: INFO | avcDaemon[1184]/avcDaemon T=main | avcAppUpdate.c NotifyObj9List() 692 | obj9ListLen; 116 obj9List: </lwm2m/9/0>,</lwm2m/9/1>,</lwm2m/9/2>,</lwm2m/9/3>,</lwm2m/9/4>,</lwm2m/9/5>,</lwm2m/9/6>,</lwm2m/9/7>,</lwm2m/9/8> May 27 15:26:00 swi-mdm9x15 user.info Legato: INFO | avcDaemon[1184]/avcDaemon T=main | push.c push_Retry() 261 | Push Retry May 27 15:26:00 swi-mdm9x15 user.info Legato: INFO | avcDaemon[1184]/avcDaemon T=main | avcServer.c ProcessUserAgreement() 1472 | Broadcast notification to applications May 27 15:26:00 swi-mdm9x15 user.info Legato: INFO | errorService[1033]/errorHandler T=main | errorHandler.c avSessionStateHandler() 66 | AV Session State Handler 0 May 27 15:26:00 swi-mdm9x15 user.info Legato: INFO | errorService[1033]/errorHandler T=main | errorHandler.c avSessionStateHandler() 70 | Got AirVantage data session May 27 15:26:00 swi-mdm9x15 user.info Legato: INFO | photoService[1072]/photoManager T=main | photoManager.c avSessionStateHandler() 662 | AV Session State Handler 0 May 27 15:26:00 swi-mdm9x15 user.info Legato: INFO | photoService[1072]/photoManager T=main | photoManager.c avSessionStateHandler() 666 | Got AirVantage data session May 27 15:26:00 swi-mdm9x15 user.info Legato: INFO | sensorToCloud[1102]/avPublisher T=main | avPublisher.c avSessionStateHandler() 1080 | AV Session State Handler 0 May 27 15:26:00 swi-mdm9x15 user.info Legato: INFO | sensorToCloud[1102]/avPublisher T=main | avPublisher.c avSessionStateHandler() 1086 | Got AirVantage data session, starting sampling May 27 15:26:00 swi-mdm9x15 user.info Legato: INFO | avcControl[1007]/avcControl T=main | avcControl.c StatusHandler() 167 | Air Vantage agent reported update status: SESSION_STARTED May 27 15:26:00 swi-mdm9x15 user.info Legato: INFO | avcDaemon[1184]/avcDaemon T=main | avcClient.c avcClient_Connect() 828 | Session already started May 27 15:26:00 swi-mdm9x15 user.info Legato: INFO | avcDaemon[1184]/avcDaemon T=main | avcClient.c avcClient_Connect() 828 | Session already started May 27 15:26:00 swi-mdm9x15 user.info Legato: INFO | errorService[1033]/errorHandler T=main | errorHandler.c avSessionStateHandler() 66 | AV Session State Handler 0 May 27 15:26:00 swi-mdm9x15 user.info Legato: INFO | errorService[1033]/errorHandler T=main | errorHandler.c avSessionStateHandler() 70 | Got AirVantage data session May 27 15:26:00 swi-mdm9x15 user.info Legato: INFO | photoService[1072]/photoManager T=main | photoManager.c avSessionStateHandler() 662 | AV Session State Handler 0 May 27 15:26:00 swi-mdm9x15 user.info Legato: INFO | photoService[1072]/photoManager T=main | photoManager.c avSessionStateHandler() 666 | Got AirVantage data session May 27 15:26:00 swi-mdm9x15 user.info Legato: INFO | sensorToCloud[1102]/avPublisher T=main | avPublisher.c avSessionStateHandler() 1080 | AV Session State Handler 0 May 27 15:26:00 swi-mdm9x15 user.info Legato: INFO | sensorToCloud[1102]/avPublisher T=main | avPublisher.c avSessionStateHandler() 1086 | Got AirVantage data session, starting sampling May 27 15:26:00 swi-mdm9x15 user.info Legato: INFO | errorService[1033]/errorHandler T=main | errorHandler.c avSessionStateHandler() 66 | AV Session State Handler 0 May 27 15:26:00 swi-mdm9x15 user.info Legato: INFO | errorService[1033]/errorHandler T=main | errorHandler.c avSessionStateHandler() 70 | Got AirVantage data session May 27 15:26:00 swi-mdm9x15 user.info Legato: INFO | photoService[1072]/photoManager T=main | photoManager.c avSessionStateHandler() 662 | AV Session State Handler 0 May 27 15:26:00 swi-mdm9x15 user.info Legato: INFO | photoService[1072]/photoManager T=main | photoManager.c avSessionStateHandler() 666 | Got AirVantage data session May 27 15:26:00 swi-mdm9x15 user.info Legato: INFO | sensorToCloud[1102]/avPublisher T=main | avPublisher.c avSessionStateHandler() 1080 | AV Session State Handler 0 May 27 15:26:00 swi-mdm9x15 user.info Legato: INFO | sensorToCloud[1102]/avPublisher T=main | avPublisher.c avSessionStateHandler() 1086 | Got AirVantage data session, starting sampling
Thanks @parmeggianig and @dbeckwith. I have forwarded this forum thread to the Legato team. There is an existing internal issue tracking this (LE-10480), so mention that ticket if you are in contact with anyone else from Sierra Wireless.
Two quick questions
Thanks
I believe this bug still exists in all recent versions of Legato.
That’s correct. The Legato issue tracker is not public.
What are you pinging from? The device, or from a PC that the device is plugged into? If it’s the PC, it could be that the iptables aren’t set up correctly.
@Evets I’m not having an issue with the ping, but with the DNS queries which fail since the addresses stored in the /etc/resolv.conf (on the target) are out of date.
@dbeckwith @dfrey It seems that in Legato 18.03 the bug is not present. Therefore it must have been introduced between .03 and .09
Hello,
Are there any updates on this?
Thanks
On June 4th, a fix was posted to the Legato GitHub.
https://github.com/legatoproject/legato-af/commit/88bfeb89afa7c9a187142800cbf632d4341aa4b5 patches apps/tools/scripts/cm.
It looks like it is quite self-contained, so you should be able to apply this to older versions of Legato.