We don't get response of exit from data mode.

[Dmitry Krol]

Hi.

We moved to version 2.5.2. Here we encounter some problem with data mode. Sometimes we don't get response of exit from data mode.

We publish data, after we send "+++" and don't get "#XDATAMODE: 0", however we get  "#XMQTTEVT: 3,0"

In order to exit from this problem, we need to rebuild our JSON data (resend doesn't help)

Before in versions 2.1.2 and 2.4.0 everything worked fine.

[dejans]

Hi,

In order to exit from this problem, we need to rebuild our JSON data (resend doesn't help)

If you look at MQTT client AT commands, you can see that <evt_type> 3 in the response syntax of #XMQTTPUB command represents the acknowledgement for the published message with QoS 1. Value 0 indicates success. In other words, there is no failure. From which specific problem do you try to exit? Can you elaborate?

Best regards,
Dejan

[Dmitry Krol]

From problem where we don't get "#XDATAMODE: 0" and modem stuck

[dejans]

Hi,

Can you elaborate what happens with the modem?

Best regards,
Dejan

[Ofir_A]

Hi Dejan,

I'll try to elaborate the situation

Currently we are using MFW 1.3.5 with SLM 2.4.0 and we have no problem at all

We have 3 types of data sending (all of them are in data mode)

regular data

raw data

trace data

now we want to advance to SLM 2.5.2 and we encounter problems sending data in data mode

1. After sending number of messages we don't receive the confirmation of exiting data mode (we exit every message and then publish again the next message). the modem seems to stay in data mode since we receive the confirmation 3,0. we tried to resend the '+++' termination with no success. so in order to exit this mode we reset the modem and afterwards we not succeeding in transmission anything until we clear our data in flash (the modem never return data mode: 0 and not answering to AT ping command)

2. We occasionally receive error on the connection to MQTT or subscribe and we need to reset our product to resolve this until the next time

these 2 major problems didn't happen in SLM 2.4.0
So we would like to know what would be the reason to this

[dejans]

Hi,

Thank you for providing additional information.

Could you send your application log?

Best regards,
Dejan

[Dmitry Krol]

Fullscreen live_log5.txt Download

[4/6 10:45:25:565] net_test
[4/6 10:45:25:579] TERM: trmnl_ExecuteCommand [395]-> Command: net_test (length without parameters: 8)
[4/6 10:45:25:579] TERM: trmnl_ExecuteCommand [405]-> Commands[i].Name: net_test
[4/6 10:45:25:580] 
[4/6 10:45:25:580] TERM: CmdNetTest [1158]-> Net test will start in 1min
[4/6 10:45:25:583] TERM: trmnl_ExecuteCommand [408]-> CMD EXECUTE OK
[4/6 10:45:25:590] APP: app_task [687]-> App event flag: APP_EVENT_NET_TEST
[4/6 10:45:43:757] APP: accel_measure_tempr [219]-> Tempr: body=25.71 amb=-57.18
[4/6 10:45:43:783] APP: app_task [687]-> App event flag: APP_EVENT_ACCEL_READY
[4/6 10:45:43:783] WI-FI: lwip_task [542]-> Wi-Fi event flag: COMM_CHECK_CONNECTION
[4/6 10:45:43:783] WI-FI: lwip_wifi_on [2064]-> Disable power save
[4/6 10:45:43:785] WI-FI: lwip_wifi_network_exist [797]-> There are not saved wifi networks -> will not try to connect
[4/6 10:45:43:799] DATA_TRANS ERROR: data_trans_network_open [408]-> Connection fail, err code: 0
[4/6 10:45:43:799] WI-FI: lwip_task [542]-> Wi-Fi event flag: COMM_OPER_STOP
[4/6 10:45:43:807] WI-FI: lwip_process_disconnect [2125]-> Enable power save
[4/6 10:45:43:814] WI-FI: lwip_wifi_off [2051]-> Enable power save
[4/6 10:45:43:822] WI-FI ERROR: lwip_process_disconnect [2131]-> sl_wfx_send_disconnect_command status: 2840
[4/6 10:45:44:051] CELLULAR: nrf_wake_up [1279]-> Wake Up nRF
[4/6 10:45:44:053] CELLULAR: nrf_check_answer [761]-> Ready
[4/6 10:45:44:053] 
[4/6 10:45:44:053] CELLULAR: nrf_get_coordinates [2998]-> The "location_measure_flag" flag: 1, the "get_gps_flag" flag: 1
[4/6 10:45:46:079] WI-FI: lwip_scan [700]-> #  1  1 44 -70 A0:4F:D4:3E:41:E1  Cellcom-WiFi_4837
[4/6 10:45:46:087] WI-FI: lwip_scan [700]-> #  2  1 44 -46 E8:1C:BA:2C:2A:10  Petpace
[4/6 10:45:46:094] WI-FI: lwip_scan [700]-> #  3  1 84 -94 CC:DB:93:0D:D8:80  LMDWWIFI
[4/6 10:45:46:102] WI-FI: lwip_scan [700]-> #  4  1 44 -83 60:32:B1:43:90:AF  Triple C 2.4
[4/6 10:45:46:109] WI-FI: lwip_scan [700]-> #  5  1 00 -93 CC:DB:93:0D:D8:82  JJGUEST
[4/6 10:45:46:117] WI-FI: lwip_scan [700]-> #  6  1 84 -94 CC:DB:93:0D:D8:83  JJWIFI
[4/6 10:45:46:124] WI-FI: lwip_scan [700]-> #  7  6 44 -94 30:E1:71:D8:2C:9B  DIRECT-9A-HP OfficeJet Pro 8710
[4/6 10:45:46:135] WI-FI: lwip_scan [700]-> #  8  6 00 -86 4A:81:D4:8D:A0:9B  ToysShefaim
[4/6 10:45:46:140] WI-FI: lwip_scan [700]-> #  9 11 44 -82 BC:9F:E4:54:EB:62  RecordOffice
[4/6 10:45:46:162] WI-FI: lwip_scan [700]-> # 10 11 00 -89 80:2D:BF:81:44:65  JJINTERNET
[4/6 10:45:46:162] WI-FI: lwip_scan [700]-> # 11  3 46 -51 0C:80:63:BA:AB:E1  aninim Home
[4/6 10:45:46:164] WI-FI: lwip_scan [700]-> # 12  3 44 -73 56:6C:EB:A4:AA:88  DIRECT-BHPET-LPT-MARINAmsXN
[4/6 10:45:46:172] WI-FI: lwip_scan [700]-> # 13  6 00 -56 04:A1:51:CB:0D:9D  
[4/6 10:45:46:179] WI-FI: lwip_scan [700]-> # 14  6 44 -90 78:8A:20:5A:93:E5  NETWORKP
[4/6 10:45:46:184] WI-FI: lwip_scan [700]-> # 15  6 44 -93 84:C9:B2:B7:5F:73  Jchil_jc6
[4/6 10:45:46:199] WI-FI: lwip_scan [700]-> # 16 11 00 -88 AC:F1:DF:18:58:A5  BezeqNGN_003567_2.4GHz_1
[4/6 10:45:46:199] WI-FI: lwip_scan [700]-> # 17  1 44 -74 A6:34:D9:40:86:CF  DIRECT-CCREDDEVILmsRI
[4/6 10:45:46:209] WI-FI: lwip_scan [700]-> # 18  6 44 -87 94:F3:92:76:49:A8  Aquapass
[4/6 10:45:46:219] CELLULAR: nrf_send_at_cmd [648]-> AT+CEREG=5
[4/6 10:45:46:222] 
[4/6 10:45:46:240] CELLULAR: nrf_check_answer [761]-> OK
[4/6 10:45:46:244] 
[4/6 10:45:46:244] CELLULAR: nrf_send_at_cmd [648]-> AT%XEMPR=1,0,2
[4/6 10:45:46:249] 
[4/6 10:45:46:264] CELLULAR: nrf_check_answer [761]-> OK
[4/6 10:45:46:269] 
[4/6 10:45:46:269] CELLULAR: nrf_send_at_cmd [648]-> AT+CSCON?
[4/6 10:45:46:272] 
[4/6 10:45:46:303] CELLULAR: nrf_check_answer [761]-> +CSCON: 0,0
[4/6 10:45:46:303] 
[4/6 10:45:46:303] OK
[4/6 10:45:46:303] 
[4/6 10:45:46:303] CELLULAR: nrf_send_at_cmd [648]-> AT+CSCON=1
[4/6 10:45:46:307] 
[4/6 10:45:46:325] CELLULAR: nrf_check_answer [761]-> OK
[4/6 10:45:46:325] 
[4/6 10:45:46:327] CELLULAR: nrf_send_at_cmd [648]-> AT+CFUN?
[4/6 10:45:46:327] 
[4/6 10:45:46:350] CELLULAR: nrf_check_answer [761]-> +CFUN: 0
[4/6 10:45:46:355] 
[4/6 10:45:46:355] OK
[4/6 10:45:46:355] 
[4/6 10:45:46:357] CELLULAR: nrf_send_at_cmd [648]-> AT+CFUN=1
[4/6 10:45:46:362] 
[4/6 10:45:47:433] CELLULAR: nrf_check_answer [744]-> 
[4/6 10:45:47:437] OK
[4/6 10:45:47:437] 
[4/6 10:45:48:528] CELLULAR: nrf_check_answer [744]-> 
[4/6 10:45:48:528] +CEREG: 2,"198D","00048801",7
[4/6 10:45:48:528] 
[4/6 10:45:48:528] +CSCON: 1
[4/6 10:45:48:529] 
[4/6 10:45:48:707] CELLULAR: nrf_check_answer [761]-> +CEREG: 5,"198D","00048801",7,,,"
[4/6 10:45:48:708] CELLULAR: nrf_send_at_cmd [648]-> AT+CEREG?
[4/6 10:45:48:708] 
[4/6 10:45:48:710] CELLULAR: nrf_check_answer [761]-> +CEREG: 5,5,"198D","00048801",7,,,"11100000","11100000"
[4/6 10:45:48:710] 
OK
[4/6 10:45:48:711] 
[4/6 10:45:48:711] CELLULAR: nrf_send_at_cmd [648]-> AT+COPS?
[4/6 10:45:48:711] 
[4/6 10:45:48:712] CELLULAR: nrf_check_answer [761]-> +COPS: 0,2,"42503",7
[4/6 10:45:48:712] 
[4/6 10:45:48:712] OK
[4/6 10:45:48:712] 
[4/6 10:45:48:712] CELLULAR: nrf_send_at_cmd [648]-> AT%XCBAND
[4/6 10:45:48:712] 
[4/6 10:45:48:712] CELLULAR: nrf_check_answer [761]-> %XCBAND: 3
[4/6 10:45:48:712] 
[4/6 10:45:48:712] OK
[4/6 10:45:48:712] 
[4/6 10:45:48:712] CELLULAR: nrf_send_at_cmd [648]-> AT#XMQTTCON=1,"
[4/6 10:45:48:713] CELLULAR: nrf_send_at_cmd [648]-> PLU39236205
[4/6 10:45:48:714] CELLULAR: nrf_send_at_cmd [648]-> ","","","a34k7wa09ujucc-ats.iot.us-east-1.amazonaws.com",8883,321
[4/6 10:45:48:714] 
[4/6 10:45:51:512] CELLULAR: nrf_check_answer [761]-> #XMQTTEVT: 0,0
[4/6 10:45:51:524] 
[4/6 10:45:51:524] CELLULAR: nrf_send_at_cmd [648]-> AT+CESQ
[4/6 10:45:51:524] 
[4/6 10:45:51:534] CELLULAR: nrf_check_answer [761]-> +CESQ: 99,99,255,255,22,30
[4/6 10:45:51:542] 
[4/6 10:45:51:542] OK
[4/6 10:45:51:542] 
[4/6 10:45:51:565] OUT JSON: data_trans_task [263]-> {"deviceId":"PLU39236205","deviceType":"Collar","requestTime":1717487148,"carrier":"CELLULAR","rssi":-93,"messages":[{"messageId":1,"messageCategory":1,"messageType":11,"recordedTime":1717487148,"firmwareVersion":"collar-fw-1.10.3","bootVersion":"Not_Found","nrfModemVer":"nrf9160_1.3.5","nrfSdkVer":"2.5.2","attachmentStatus":"Off-Pet","battery":13,"volt":3.849,"band":3,"provider":"42503","chargeStatus":"Charging","collarStatus":"ON","operationMode":2,"debugMode":6,"sosMode":0},{"messageId":2,"messageCategory":1,"messageType":9,"recordedTime":1717487076,"fragmentNumber":1,"packetCurrent":1,"message":"CMD EXECUTE OK"},{"messageId":3,"messageCategory":1,"messageType":9,"recordedTime":1717487076,"fragmentNumber":2,"packetCurrent":1,"message":"App event flag: APP_EVENT_PARAM_BACKUP"},{"messageId":4,"messageCategory":1,"messageType":9,"recordedTime":1717487078,"fragmentNumber":3,"packetCurrent":1,"message":"Tempr: body=25.21 amb=-57.18"},{"messageId":5,"messageCategory":1,"messageType":12,"recordedTime":1717487078,"offPet":"Active"},{"messageId":6,"messageCategory":1,"messageType":9,"recordedTime":1717487078,"fragmentNumber":4,"packetCurrent":1,"message":"App event flag: APP_EVENT_ACCEL_READY"},{"messageId":7,"messageCategory":1,"messageType":9,"recordedTime":1717487078,"fragmentNumber":5,"packetCurrent":1,"message":"Command: reset (length without parameters: 5)"},{"messageId":8,"messageCategory":1,"messageType":9,"recordedTime":1717487078,"fragmentNumber":6,"packetCurrent":1,"message":"Commands[i].Name: reset"},{"messageId":9,"messageCategory":1,"messageType":9,"recordedTime":1717487078,"fragmentNumber":7,"packetCurrent":1,"message":"CMD EXECUTE OK"},{"messageId":10,"messageCategory":1,"messageType":9,"recordedTime":1717487078,"fragmentNumber":8,"packetCurrent":1,"message":"App event flag: APP_EVENT_RESET"},{"messageId":11,"messageCategory":1,"messageType":9,"recordedTime":1717487078,"fragmentNumber":9,"packetCurrent":1,"message":"System Reset"}]}
[4/6 10:45:51:811] CELLULAR: nrf_send_at_cmd [648]-> AT+CESQ
[4/6 10:45:51:811] 
CELLULAR: nrf_check_answer [761]-> +CESQ: 99,99,255,255,20,30
[4/6 10:45:51:813] 
[4/6 10:45:51:813] OK

[4/6 10:45:51:813] CELLULAR: nrf_send_at_cmd [648]-> AT#XMQTTPUB="
[4/6 10:45:51:814] CELLULAR: nrf_send_at_cmd [648]-> Staging/ToServer/v2/Operational
[4/6 10:45:51:815] CELLULAR: nrf_send_at_cmd [648]-> ","",1
[4/6 10:45:51:815] 
[4/6 10:45:51:817] CELLULAR: nrf_check_answer [761]-> OK
[4/6 10:45:51:817] 
[4/6 10:45:52:062] CELLULAR: nrf_send_at_cmd [648]-> +++
[4/6 10:45:52:063] CELLULAR: nrf_check_answer [761]-> #XDATAMODE: 0
[4/6 10:45:52:064] 
[4/6 10:45:52:593] CELLULAR: nrf_check_answer [761]-> #XMQTTEVT: 3,0
[4/6 10:45:52:597] 
[4/6 10:45:52:619] OUT JSON: data_trans_task [263]-> {"deviceId":"PLU39236205","deviceType":"Collar","requestTime":1717487149,"carrier":"CELLULAR","rssi":-92,"messages":[{"messageId":12,"messageCategory":1,"messageType":9,"recordedTime":1717487078,"fragmentNumber":10,"packetCurrent":1,"message":"Reset reason 2"},{"messageId":13,"messageCategory":1,"messageType":10,"recordedTime":1717487078,"errorCode":153,"errorState":"Active"},{"messageId":14,"messageCategory":1,"messageType":9,"recordedTime":1717487078,"fragmentNumber":11,"packetCurrent":1,"message":"Battery volt: v=3.849, percent=13"},{"messageId":15,"messageCategory":1,"messageType":9,"recordedTime":1717487078,"fragmentNumber":12,"packetCurrent":1,"message":"Tempr: body=25.28 amb=-57.18"},{"messageId":16,"messageCategory":1,"messageType":21,"recordedTime":1717487078},{"messageId":17,"messageCategory":1,"messageType":9,"recordedTime":1717487122,"fragmentNumber":13,"packetCurrent":1,"message":"Command: net_test (length without parameters: 8)"},{"messageId":18,"messageCategory":1,"messageType":9,"recordedTime":1717487122,"fragmentNumber":14,"packetCurrent":1,"message":"Commands[i].Name: net_test"},{"messageId":19,"messageCategory":1,"messageType":9,"recordedTime":1717487122,"fragmentNumber":15,"packetCurrent":1,"message":"Net test will start in 1min"},{"messageId":20,"messageCategory":1,"messageType":9,"recordedTime":1717487122,"fragmentNumber":16,"packetCurrent":1,"message":"CMD EXECUTE OK"},{"messageId":21,"messageCategory":1,"messageType":9,"recordedTime":1717487122,"fragmentNumber":17,"packetCurrent":1,"message":"App event flag: APP_EVENT_NET_TEST"},{"messageId":22,"messageCategory":1,"messageType":9,"recordedTime":1717487141,"fragmentNumber":18,"packetCurrent":1,"message":"Tempr: body=25.71 amb=-57.18"},{"messageId":23,"messageCategory":1,"messageType":12,"recordedTime":1717487141,"offPet":"Active"}]}
[4/6 10:45:52:782] CELLULAR: nrf_send_at_cmd [648]-> AT+CESQ
[4/6 10:45:52:788] 
[4/6 10:45:52:799] CELLULAR: nrf_check_answer [761]-> +CESQ: 99,99,255,255,19,31
[4/6 10:45:52:802] 
[4/6 10:45:52:807] OK
[4/6 10:45:52:807] 
[4/6 10:45:52:807] CELLULAR: nrf_send_at_cmd [648]-> AT#XMQTTPUB="
[4/6 10:45:52:820] CELLULAR: nrf_send_at_cmd [648]-> Staging/ToServer/v2/Operational
[4/6 10:45:52:832] CELLULAR: nrf_send_at_cmd [648]-> ","",1
[4/6 10:45:52:837] 
[4/6 10:45:52:847] CELLULAR: nrf_check_answer [761]-> OK
[4/6 10:45:52:852] 
[4/6 10:45:53:116] CELLULAR: nrf_send_at_cmd [648]-> +++
[4/6 10:45:53:117] CELLULAR: nrf_check_answer [761]-> #XDATAMODE: 0
[4/6 10:45:53:117] 
[4/6 10:45:53:749] CELLULAR: nrf_check_answer [761]-> #XMQTTEVT: 3,0
[4/6 10:45:53:752] 
[4/6 10:45:53:774] OUT JSON: data_trans_task [263]-> {"deviceId":"PLU39236205","deviceType":"Collar","requestTime":1717487151,"carrier":"CELLULAR","rssi":-91,"messages":[{"messageId":24,"messageCategory":1,"messageType":9,"recordedTime":1717487141,"fragmentNumber":19,"packetCurrent":1,"message":"App event flag: APP_EVENT_ACCEL_READY"},{"messageId":25,"messageCategory":1,"messageType":9,"recordedTime":1717487141,"fragmentNumber":20,"packetCurrent":1,"message":"Wi-Fi event flag: COMM_CHECK_CONNECTION"},{"messageId":26,"messageCategory":1,"messageType":9,"recordedTime":1717487141,"fragmentNumber":21,"packetCurrent":1,"message":"Disable power save"},{"messageId":27,"messageCategory":1,"messageType":9,"recordedTime":1717487141,"fragmentNumber":22,"packetCurrent":1,"message":"There are not saved wifi networks -> will not try to connect"},{"messageId":28,"messageCategory":1,"messageType":9,"recordedTime":1717487141,"fragmentNumber":23,"packetCurrent":1,"message":"Connection fail, err code: 0"},{"messageId":29,"messageCategory":1,"messageType":9,"recordedTime":1717487141,"fragmentNumber":24,"packetCurrent":1,"message":"Wi-Fi event flag: COMM_OPER_STOP"},{"messageId":30,"messageCategory":1,"messageType":9,"recordedTime":1717487141,"fragmentNumber":25,"packetCurrent":1,"message":"Enable power save"},{"messageId":31,"messageCategory":1,"messageType":9,"recordedTime":1717487141,"fragmentNumber":26,"packetCurrent":1,"message":"Enable power save"},{"messageId":32,"messageCategory":1,"messageType":9,"recordedTime":1717487141,"fragmentNumber":27,"packetCurrent":1,"message":"sl_wfx_send_disconnect_command status: 2840"},{"messageId":33,"messageCategory":1,"messageType":9,"recordedTime":1717487141,"fragmentNumber":28,"packetCurrent":1,"message":"Wake Up nRF"},{"messageId":34,"messageCategory":1,"messageType":9,"recordedTime":1717487141,"fragmentNumber":29,"packetCurrent":1,"message":"Ready  "}]}
[4/6 10:45:53:944] CELLULAR: nrf_send_at_cmd [648]-> AT+CESQ
[4/6 10:45:53:944] 
[4/6 10:45:54:173] CELLULAR: nrf_check_answer [761]-> +CESQ: 99,99,255,255,17,30

[4/6 10:45:54:173] OK
[4/6 10:45:54:173] 
[4/6 10:45:54:174] CELLULAR: nrf_send_at_cmd [648]-> AT#XMQTTPUB="
[4/6 10:45:54:176] CELLULAR: nrf_send_at_cmd [648]-> Staging/ToServer/v2/Operational
[4/6 10:45:54:178] CELLULAR: nrf_send_at_cmd [648]-> ","",1
[4/6 10:45:54:178] 
[4/6 10:45:54:178] CELLULAR: nrf_check_answer [761]-> OK
[4/6 10:45:54:178] 
[4/6 10:45:54:218] CELLULAR: nrf_send_at_cmd [648]-> +++
[4/6 10:45:54:243] CELLULAR: nrf_check_answer [761]-> #XDATAMODE: 0
[4/6 10:45:54:247] 
[4/6 10:45:54:920] CELLULAR: nrf_check_answer [761]-> #XMQTTEVT: 3,0
[4/6 10:45:54:923] 
[4/6 10:45:54:944] OUT JSON: data_trans_task [263]-> {"deviceId":"PLU39236205","deviceType":"Collar","requestTime":1717487152,"carrier":"CELLULAR","rssi":-91,"messages":[{"messageId":35,"messageCategory":1,"messageType":9,"recordedTime":1717487141,"fragmentNumber":30,"packetCurrent":1,"message":"The  location_measure_flag  flag: 1, the  get_gps_flag  flag: 1"},{"messageId":36,"messageCategory":1,"messageType":9,"recordedTime":1717487143,"fragmentNumber":31,"packetCurrent":1,"message":"#  1  1 44 -70 A0:4F:D4:3E:41:E1  Cellcom-WiFi_4837"},{"messageId":37,"messageCategory":1,"messageType":9,"recordedTime":1717487143,"fragmentNumber":32,"packetCurrent":1,"message":"#  2  1 44 -46 E8:1C:BA:2C:2A:10  Petpace"},{"messageId":38,"messageCategory":1,"messageType":9,"recordedTime":1717487143,"fragmentNumber":33,"packetCurrent":1,"message":"#  3  1 84 -94 CC:DB:93:0D:D8:80  LMDWWIFI"},{"messageId":39,"messageCategory":1,"messageType":9,"recordedTime":1717487143,"fragmentNumber":34,"packetCurrent":1,"message":"#  4  1 44 -83 60:32:B1:43:90:AF  Triple C 2.4"},{"messageId":40,"messageCategory":1,"messageType":9,"recordedTime":1717487143,"fragmentNumber":35,"packetCurrent":1,"message":"#  5  1 00 -93 CC:DB:93:0D:D8:82  JJGUEST"},{"messageId":41,"messageCategory":1,"messageType":9,"recordedTime":1717487143,"fragmentNumber":36,"packetCurrent":1,"message":"#  6  1 84 -94 CC:DB:93:0D:D8:83  JJWIFI"},{"messageId":42,"messageCategory":1,"messageType":9,"recordedTime":1717487143,"fragmentNumber":37,"packetCurrent":1,"message":"#  7  6 44 -94 30:E1:71:D8:2C:9B  DIRECT-9A-HP OfficeJet Pro 8710"},{"messageId":43,"messageCategory":1,"messageType":9,"recordedTime":1717487143,"fragmentNumber":38,"packetCurrent":1,"message":"#  8  6 00 -86 4A:81:D4:8D:A0:9B  ToysShefaim"},{"messageId":44,"messageCategory":1,"messageType":9,"recordedTime":1717487143,"fragmentNumber":39,"packetCurrent":1,"message":"#  9 11 44 -82 BC:9F:E4:54:EB:62  RecordOffice"}]}
[4/6 10:45:55:112] CELLULAR: nrf_send_at_cmd [648]-> AT+CESQ
[4/6 10:45:55:117] 
[4/6 10:45:55:129] CELLULAR: nrf_check_answer [761]-> +CESQ: 99,99,255,255,21,30
[4/6 10:45:55:129] 
[4/6 10:45:55:138] OK
[4/6 10:45:55:138] 
[4/6 10:45:55:138] CELLULAR: nrf_send_at_cmd [648]-> AT#XMQTTPUB="
[4/6 10:45:55:147] CELLULAR: nrf_send_at_cmd [648]-> Staging/ToServer/v2/Operational
[4/6 10:45:55:162] CELLULAR: nrf_send_at_cmd [648]-> ","",1
[4/6 10:45:55:162] 
[4/6 10:45:55:176] CELLULAR: nrf_check_answer [761]-> OK
[4/6 10:45:55:176] 
[4/6 10:45:55:466] CELLULAR: nrf_send_at_cmd [648]-> +++
[4/6 10:45:55:468] CELLULAR: nrf_check_answer [761]-> #XDATAMODE: 0
[4/6 10:45:55:468] 
[4/6 10:45:55:957] CELLULAR: nrf_check_answer [761]-> #XMQTTEVT: 3,0

[4/6 10:45:55:984] OUT JSON: data_trans_task [263]-> {"deviceId":"PLU39236205","deviceType":"Collar","requestTime":1717487153,"carrier":"CELLULAR","rssi":-93,"messages":[{"messageId":45,"messageCategory":1,"messageType":9,"recordedTime":1717487143,"fragmentNumber":40,"packetCurrent":1,"message":"# 10 11 00 -89 80:2D:BF:81:44:65  JJINTERNET"},{"messageId":46,"messageCategory":1,"messageType":9,"recordedTime":1717487143,"fragmentNumber":41,"packetCurrent":1,"message":"# 11  3 46 -51 0C:80:63:BA:AB:E1  aninim Home"},{"messageId":47,"messageCategory":1,"messageType":9,"recordedTime":1717487143,"fragmentNumber":42,"packetCurrent":1,"message":"# 12  3 44 -73 56:6C:EB:A4:AA:88  DIRECT-BHPET-LPT-MARINAmsXN"},{"messageId":48,"messageCategory":1,"messageType":9,"recordedTime":1717487143,"fragmentNumber":43,"packetCurrent":1,"message":"# 13  6 00 -56 04:A1:51:CB:0D:9D  "},{"messageId":49,"messageCategory":1,"messageType":9,"recordedTime":1717487143,"fragmentNumber":44,"packetCurrent":1,"message":"# 14  6 44 -90 78:8A:20:5A:93:E5  NETWORKP"},{"messageId":50,"messageCategory":1,"messageType":9,"recordedTime":1717487143,"fragmentNumber":45,"packetCurrent":1,"message":"# 15  6 44 -93 84:C9:B2:B7:5F:73  Jchil_jc6"},{"messageId":51,"messageCategory":1,"messageType":9,"recordedTime":1717487143,"fragmentNumber":46,"packetCurrent":1,"message":"# 16 11 00 -88 AC:F1:DF:18:58:A5  BezeqNGN_003567_2.4GHz_1"},{"messageId":52,"messageCategory":1,"messageType":9,"recordedTime":1717487143,"fragmentNumber":47,"packetCurrent":1,"message":"# 17  1 44 -74 A6:34:D9:40:86:CF  DIRECT-CCREDDEVILmsRI"},{"messageId":53,"messageCategory":1,"messageType":9,"recordedTime":1717487143,"fragmentNumber":48,"packetCurrent":1,"message":"# 18  6 44 -87 94:F3:92:76:49:A8  Aquapass"},{"messageId":54,"messageCategory":1,"messageType":9,"recordedTime":1717487143,"fragmentNumber":49,"packetCurrent":1,"message":"AT+CEREG=5  "},{"messageId":55,"messageCategory":1,"messageType":9,"recordedTime":1717487143,"fragmentNumber":50,"packetCurrent":1,"message":"OK  "}]}
[4/6 10:45:56:162] CELLULAR: nrf_send_at_cmd [648]-> AT+CESQ
[4/6 10:45:56:162] 
[4/6 10:45:56:177] CELLULAR: nrf_check_answer [761]-> +CESQ: 99,99,255,255,22,30
[4/6 10:45:56:184] 
[4/6 10:45:56:184] OK
[4/6 10:45:56:184] 
[4/6 10:45:56:184] CELLULAR: nrf_send_at_cmd [648]-> AT#XMQTTPUB="
[4/6 10:45:56:197] CELLULAR: nrf_send_at_cmd [648]-> Staging/ToServer/v2/Operational
[4/6 10:45:56:209] CELLULAR: nrf_send_at_cmd [648]-> ","",1
[4/6 10:45:56:212] 
[4/6 10:45:56:255] CELLULAR: nrf_check_answer [761]-> OK
[4/6 10:45:56:255] 
[4/6 10:45:56:497] CELLULAR: nrf_send_at_cmd [648]-> +++
[4/6 10:45:56:499] CELLULAR: nrf_check_answer [761]-> #XDATAMODE: 0
[4/6 10:45:56:499] 
[4/6 10:45:57:159] CELLULAR: nrf_check_answer [761]-> #XMQTTEVT: 3,0
[4/6 10:45:57:162] 
[4/6 10:45:57:185] OUT JSON: data_trans_task [263]-> {"deviceId":"PLU39236205","deviceType":"Collar","requestTime":1717487154,"carrier":"CELLULAR","rssi":-93,"messages":[{"messageId":56,"messageCategory":1,"messageType":9,"recordedTime":1717487143,"fragmentNumber":51,"packetCurrent":1,"message":"AT%XEMPR=1,0,2  "},{"messageId":57,"messageCategory":1,"messageType":9,"recordedTime":1717487143,"fragmentNumber":52,"packetCurrent":1,"message":"OK  "},{"messageId":58,"messageCategory":1,"messageType":9,"recordedTime":1717487143,"fragmentNumber":53,"packetCurrent":1,"message":"AT+CSCON?  "},{"messageId":59,"messageCategory":1,"messageType":9,"recordedTime":1717487143,"fragmentNumber":54,"packetCurrent":1,"message":"+CSCON: 0,0    OK  "},{"messageId":60,"messageCategory":1,"messageType":9,"recordedTime":1717487143,"fragmentNumber":55,"packetCurrent":1,"message":"AT+CSCON=1  "},{"messageId":61,"messageCategory":1,"messageType":9,"recordedTime":1717487143,"fragmentNumber":56,"packetCurrent":1,"message":"OK  "},{"messageId":62,"messageCategory":1,"messageType":9,"recordedTime":1717487143,"fragmentNumber":57,"packetCurrent":1,"message":"AT+CFUN?  "},{"messageId":63,"messageCategory":1,"messageType":9,"recordedTime":1717487143,"fragmentNumber":58,"packetCurrent":1,"message":"+CFUN: 0    OK  "},{"messageId":64,"messageCategory":1,"messageType":9,"recordedTime":1717487143,"fragmentNumber":59,"packetCurrent":1,"message":"AT+CFUN=1  "},{"messageId":65,"messageCategory":1,"messageType":9,"recordedTime":1717487144,"fragmentNumber":60,"packetCurrent":1,"message":"  OK  "},{"messageId":66,"messageCategory":1,"messageType":9,"recordedTime":1717487145,"fragmentNumber":61,"packetCurrent":1,"message":"  +CEREG: 2, 198D , 00048801 ,7    +CSCON: 1  "},{"messageId":67,"messageCategory":1,"messageType":9,"recordedTime":1717487146,"fragmentNumber":62,"packetCurrent":1,"message":"+CEREG: 5, 198D , 00048801 ,7,,, "}]}
[4/6 10:45:57:352] CELLULAR: nrf_send_at_cmd [648]-> AT+CESQ
[4/6 10:45:57:357] 
[4/6 10:45:57:637] CELLULAR: nrf_check_answer [761]-> +CESQ: 99,99,255,255,20,30
[4/6 10:45:57:637] 
[4/6 10:45:57:637] OK
[4/6 10:45:57:637] 
[4/6 10:45:57:637] CELLULAR: nrf_send_at_cmd [648]-> AT#XMQTTPUB="
[4/6 10:45:57:637] CELLULAR: nrf_send_at_cmd [648]-> Staging/ToServer/v2/Operational
[4/6 10:45:57:639] CELLULAR: nrf_send_at_cmd [648]-> ","",1
[4/6 10:45:57:640] 
[4/6 10:45:57:641] CELLULAR: nrf_check_answer [761]-> OK
[4/6 10:45:57:646] 
[4/6 10:45:57:647] CELLULAR: nrf_send_at_cmd [648]-> +++
[4/6 10:45:57:647] CELLULAR: nrf_check_answer [761]-> #XDATAMODE: 0
[4/6 10:45:57:647] 
[4/6 10:45:58:181] CELLULAR: nrf_check_answer [761]-> #XMQTTEVT: 3,0
[4/6 10:45:58:187] 
[4/6 10:45:58:209] OUT JSON: data_trans_task [263]-> {"deviceId":"PLU39236205","deviceType":"Collar","requestTime":1717487155,"carrier":"CELLULAR","rssi":-92,"messages":[{"messageId":68,"messageCategory":1,"messageType":9,"recordedTime":1717487146,"fragmentNumber":63,"packetCurrent":1,"message":"AT+CEREG?  "},{"messageId":69,"messageCategory":1,"messageType":9,"recordedTime":1717487146,"fragmentNumber":64,"packetCurrent":1,"message":"+CEREG: 5,5, 198D , 00048801 ,7,,, 11100000 , 11100000     OK  "},{"messageId":70,"messageCategory":1,"messageType":9,"recordedTime":1717487146,"fragmentNumber":65,"packetCurrent":1,"message":"AT+COPS?  "},{"messageId":71,"messageCategory":1,"messageType":9,"recordedTime":1717487146,"fragmentNumber":66,"packetCurrent":1,"message":"+COPS: 0,2, 42503 ,7    OK  "},{"messageId":72,"messageCategory":1,"messageType":9,"recordedTime":1717487146,"fragmentNumber":67,"packetCurrent":1,"message":"AT%XCBAND  "},{"messageId":73,"messageCategory":1,"messageType":9,"recordedTime":1717487146,"fragmentNumber":68,"packetCurrent":1,"message":"%XCBAND: 3    OK  "},{"messageId":74,"messageCategory":1,"messageType":9,"recordedTime":1717487146,"fragmentNumber":69,"packetCurrent":1,"message":"AT#XMQTTCON=1, "},{"messageId":75,"messageCategory":1,"messageType":9,"recordedTime":1717487146,"fragmentNumber":70,"packetCurrent":1,"message":"PLU39236205"},{"messageId":76,"messageCategory":1,"messageType":9,"recordedTime":1717487146,"fragmentNumber":71,"packetCurrent":1,"message":" ,  ,  , a34k7wa09ujucc-ats.iot.us-east-1.amazonaws.com ,8883,321  "},{"messageId":77,"messageCategory":1,"messageType":9,"recordedTime":1717487148,"fragmentNumber":72,"packetCurrent":1,"message":"#XMQTTEVT: 0,0  "},{"messageId":78,"messageCategory":1,"messageType":9,"recordedTime":1717487148,"fragmentNumber":73,"packetCurrent":1,"message":"AT+CESQ  "},{"messageId":79,"messageCategory":1,"messageType":9,"recordedTime":1717487148,"fragmentNumber":74,"packetCurrent":1,"message":"+CESQ: 99,99,255,255,22,30    OK  "}]}
[4/6 10:45:58:387] CELLULAR: nrf_send_at_cmd [648]-> AT+CESQ
[4/6 10:45:58:387] 
[4/6 10:45:58:404] CELLULAR: nrf_check_answer [761]-> +CESQ: 99,99,255,255,19,30
[4/6 10:45:58:404] 
[4/6 10:45:58:404] OK
[4/6 10:45:58:404] 
[4/6 10:45:58:409] CELLULAR: nrf_send_at_cmd [648]-> AT#XMQTTPUB="
[4/6 10:45:58:442] CELLULAR: nrf_send_at_cmd [648]-> Staging/ToServer/v2/Operational
[4/6 10:45:58:444] CELLULAR: nrf_send_at_cmd [648]-> ","",1
[4/6 10:45:58:444] 
[4/6 10:45:58:453] CELLULAR: nrf_check_answer [761]-> OK
[4/6 10:45:58:453] 
[4/6 10:45:58:760] CELLULAR: nrf_send_at_cmd [648]-> +++
[4/6 10:45:58:762] CELLULAR: nrf_check_answer [761]-> #XDATAMODE: 0
[4/6 10:45:58:794] 
[4/6 10:45:59:229] CELLULAR: nrf_check_answer [761]-> #XMQTTEVT: 3,0
[4/6 10:45:59:232] 
[4/6 10:45:59:237] CELLULAR: nrf_send_at_cmd [648]-> AT#XMQTTSUB="
[4/6 10:45:59:247] CELLULAR: nrf_send_at_cmd [648]-> $aws/things/PLU39236205/shadow/update/delta
[4/6 10:45:59:252] CELLULAR: nrf_send_at_cmd [648]-> ",1
[4/6 10:45:59:257] 
[4/6 10:45:59:560] CELLULAR: nrf_check_answer [761]-> #XMQTTEVT: 7,0
[4/6 10:45:59:560] 
CELLULAR: nrf_send_configuration [1090]-> Outgoing data to "shadow/update" topic (length = 786):
{"state":{"reported":{"trigger":1,"operTopic":"Staging/ToServer/v2/Operational","timeZone":4,"userColor":16777215,"operMode":2,"debugMode":6,"sosMode":0,"debugTrace":4697,"algo":{"petType":1,"collarSize":4,"dob":1585916400,"breed":1223,"gender":2,"healthy":true,"weight":4.0,"medicalCondition":[]},"safeZone":[],"wifiSettings":[],"wifiNetworks":[{"trigger":1701964310,"ssid":"Petpace","password":[76,33,70,-85,126,88,44,33,-54,18,-18,-94,-97,126,98,-12,76,-18,46,120,29,29,-83,108,-71,54,60,-53,-112,90,-56,-92,45,3,-45,-70,-97,70,15,-128,39,68,2,-115,-72,-73,-73,72,53,-53,69,-11,116,22,-67,-13,104,-59,21,10,-119,-45,51,-39]}],"bands":[],"communicationChannels":[{"channel":"BLE","state":0},{"channel":"WIFI","state":1},{"channel":"CELLULAR","state":1},{"channel":"GPS","state":0}]}}}
[4/6 10:45:59:862] CELLULAR: nrf_send_at_cmd [648]-> AT+CESQ
[4/6 10:45:59:862] 
[4/6 10:45:59:864] CELLULAR: nrf_check_answer [761]-> +CESQ: 99,99,255,255,22,29
[4/6 10:45:59:864] 
[4/6 10:45:59:864] OK
[4/6 10:45:59:864] 
[4/6 10:45:59:864] CELLULAR: nrf_send_at_cmd [648]-> AT#XMQTTPUB="
[4/6 10:45:59:866] CELLULAR: nrf_send_at_cmd [648]-> $aws/things/PLU39236205/shadow/update
[4/6 10:45:59:866] CELLULAR: nrf_send_at_cmd [648]-> ","",1
[4/6 10:45:59:866] 
[4/6 10:45:59:867] CELLULAR: nrf_check_answer [761]-> OK
[4/6 10:45:59:867] 
[4/6 10:45:59:867] CELLULAR: nrf_send_at_cmd [648]-> +++
[4/6 10:45:59:868] CELLULAR: nrf_check_answer [761]-> #XDATAMODE: 0
[4/6 10:45:59:868] 
[4/6 10:46:00:254] CELLULAR: nrf_check_answer [761]-> #XMQTTEVT: 3,0
[4/6 10:46:00:256] 
[4/6 10:46:00:530] CELLULAR: nrf_wait_data_from_mqtt_sub [1195]-> Incoming data full size with metadata = 158 bytes
[4/6 10:46:00:533] CELLULAR: misc_unpack [464]-> Incoming data to "shadow/update/delta" topic (length = 79):
{"version":120070,"timestamp":1717487159,"state":{"trigger":0,"env":"staging"}}
[4/6 10:46:00:535] CELLULAR: misc_unpack [469]-> Data has been received and unpacked
[4/6 10:46:00:535] CELLULAR: nrf_send_at_cmd [648]-> AT#XMQTTUNSUB="
[4/6 10:46:00:536] APP: app_task [687]-> App event flag: APP_EVENT_PARAM_BACKUP
[4/6 10:46:00:536] CELLULAR: nrf_send_at_cmd [648]-> $aws/things/PLU39236205/shadow/update/delta
[4/6 10:46:00:537] CELLULAR: nrf_send_at_cmd [648]-> "
[4/6 10:46:00:537] 
[4/6 10:46:00:922] CELLULAR: nrf_check_answer [761]-> #XMQTTEVT: 8,0
[4/6 10:46:00:929] 
[4/6 10:46:00:929] CELLULAR: nrf_send_configuration [1090]-> Outgoing data to "shadow/update" topic (length = 36):
{"state":{"reported":{"trigger":2}}}
[4/6 10:46:00:937] CELLULAR: nrf_send_at_cmd [648]-> AT+CESQ
[4/6 10:46:00:942] 
[4/6 10:46:00:954] CELLULAR: nrf_check_answer [761]-> +CESQ: 99,99,255,255,16,28
[4/6 10:46:00:957] 
OK
[4/6 10:46:00:962] 
[4/6 10:46:00:962] CELLULAR: nrf_send_at_cmd [648]-> AT#XMQTTPUB="
[4/6 10:46:00:974] CELLULAR: nrf_send_at_cmd [648]-> $aws/things/PLU39236205/shadow/update
CELLULAR: nrf_send_at_cmd [648]-> ","",1
[4/6 10:46:00:992] 
[4/6 10:46:01:003] CELLULAR: nrf_check_answer [761]-> OK
[4/6 10:46:01:021] 
[4/6 10:46:01:022] CELLULAR: nrf_send_at_cmd [648]-> +++
[4/6 10:46:01:027] CELLULAR: nrf_check_answer [761]-> #XDATAMODE: 0
[4/6 10:46:01:032] 
[4/6 10:46:01:617] CELLULAR: nrf_check_answer [761]-> #XMQTTEVT: 3,0
[4/6 10:46:01:624] 
[4/6 10:46:01:624] CELLULAR: nrf_send_at_cmd [648]-> AT#XMQTTCON=0
[4/6 10:46:01:624] 
[4/6 10:46:01:652] CELLULAR: nrf_check_answer [761]-> #XMQTTEVT: 1,0
[4/6 10:46:01:657] 
[4/6 10:46:01:657] OK
[4/6 10:46:01:657] 
[4/6 10:46:01:906] CELLULAR: nrf_send_at_cmd [648]-> AT+CPSMS=0
[4/6 10:46:01:907] 
[4/6 10:46:01:908] CELLULAR: nrf_check_answer [761]-> OK
[4/6 10:46:01:908] 
[4/6 10:46:01:908] CELLULAR: nrf_send_at_cmd [648]-> AT+CFUN=0
[4/6 10:46:01:908] 
[4/6 10:46:03:326] CELLULAR: nrf_check_answer [761]-> +CEREG: 0
[4/6 10:46:03:327] 
[4/6 10:46:03:327] +CSCON: 0
[4/6 10:46:03:327] 
[4/6 10:46:03:331] CELLULAR: nrf_send_at_cmd [648]-> AT#XSLEEP=2
[4/6 10:46:03:336] 
[4/6 10:46:03:341] CELLULAR: nrf_check_answer [761]-> OK
[4/6 10:46:03:348] 
[4/6 10:46:03:348] APP: app_task [687]-> App event flag: APP_EVENT_CHECK_POWER_OFF
[4/6 10:46:04:357] WI-FI: lwip_task [542]-> Wi-Fi event flag: COMM_CHECK_CONNECTION
[4/6 10:46:04:362] WI-FI: lwip_wifi_on [2064]-> Disable power save
[4/6 10:46:04:369] WI-FI: lwip_wifi_network_exist [797]-> There are not saved wifi networks -> will not try to connect
[4/6 10:46:04:380] DATA_TRANS ERROR: data_trans_network_open [408]-> Connection fail, err code: 0
[4/6 10:46:04:387] WI-FI: lwip_task [542]-> Wi-Fi event flag: COMM_OPER_STOP
[4/6 10:46:04:396] WI-FI: lwip_process_disconnect [2125]-> Enable power save
[4/6 10:46:04:397] WI-FI: lwip_wifi_off [2051]-> Enable power save
[4/6 10:46:04:410] WI-FI ERROR: lwip_process_disconnect [2131]-> sl_wfx_send_disconnect_command status: 2840
[4/6 10:46:04:417] FOTA ERROR: fota_task [376]-> APP_MQTT_FOTA_NOTIFY fail
[4/6 10:46:04:422] APP: app_task [687]-> App event flag: APP_EVENT_ACTIVE_FLOW
[4/6 10:46:08:791] net_test
[4/6 10:46:08:795] TERM: trmnl_ExecuteCommand [395]-> Command: net_test (length without parameters: 8)
[4/6 10:46:08:803] TERM: trmnl_ExecuteCommand [405]-> Commands[i].Name: net_test
[4/6 10:46:08:803] 
[4/6 10:46:08:803] TERM: CmdNetTest [1158]-> Net test will start in 1min
[4/6 10:46:08:803] TERM: trmnl_ExecuteCommand [408]-> CMD EXECUTE OK
[4/6 10:46:08:812] APP: app_task [687]-> App event flag: APP_EVENT_NET_TEST
[4/6 10:46:47:452] APP: accel_measure_tempr [219]-> Tempr: body=26.13 amb=-57.18
[4/6 10:46:47:457] APP: app_task [687]-> App event flag: APP_EVENT_ACCEL_READY
[4/6 10:46:47:468] WI-FI: lwip_task [542]-> Wi-Fi event flag: COMM_CHECK_CONNECTION
[4/6 10:46:47:472] WI-FI: lwip_wifi_on [2064]-> Disable power save
[4/6 10:46:47:483] WI-FI: lwip_wifi_network_exist [797]-> There are not saved wifi networks -> will not try to connect
[4/6 10:46:47:492] DATA_TRANS ERROR: data_trans_network_open [408]-> Connection fail, err code: 0
[4/6 10:46:47:497] WI-FI: lwip_task [542]-> Wi-Fi event flag: COMM_OPER_STOP
[4/6 10:46:47:503] WI-FI: lwip_process_disconnect [2125]-> Enable power save
WI-FI: lwip_wifi_off [2051]-> Enable power save
[4/6 10:46:47:517] WI-FI ERROR: lwip_process_disconnect [2131]-> sl_wfx_send_disconnect_command status: 2840
[4/6 10:46:47:797] CELLULAR: nrf_wake_up [1279]-> Wake Up nRF
[4/6 10:46:47:798] CELLULAR: nrf_check_answer [761]-> Ready
[4/6 10:46:47:799] 
[4/6 10:46:47:799] CELLULAR: nrf_get_coordinates [2998]-> The "location_measure_flag" flag: 1, the "get_gps_flag" flag: 1
[4/6 10:46:49:822] WI-FI: lwip_scan [700]-> #  1  1 44 -74 A0:4F:D4:3E:41:E1  Cellcom-WiFi_4837
[4/6 10:46:49:827] WI-FI: lwip_scan [700]-> #  2  1 44 -47 E8:1C:BA:2C:2A:10  Petpace
[4/6 10:46:49:837] WI-FI: lwip_scan [700]-> #  3  1 84 -94 3C:13:CC:FE:B3:A0  LMDWWIFI
[4/6 10:46:49:842] WI-FI: lwip_scan [700]-> #  4  1 44 -79 60:32:B1:43:90:AF  Triple C 2.4
[4/6 10:46:49:853] WI-FI: lwip_scan [700]-> #  5  6 00 -82 4A:81:D4:8D:A0:9B  ToysShefaim
[4/6 10:46:49:856] WI-FI: lwip_scan [700]-> #  6  6 84 -76 90:77:EE:DF:83:C3  JJWIFI
[4/6 10:46:49:865] WI-FI: lwip_scan [700]-> #  7  6 00 -77 90:77:EE:DF:83:C5  JJINTERNET
[4/6 10:46:49:877] WI-FI: lwip_scan [700]-> #  8  6 00 -56 04:A1:51:CB:0D:9D  
[4/6 10:46:49:877] WI-FI: lwip_scan [700]-> #  9 11 00 -90 AC:F1:DF:18:58:A5  BezeqNGN_003567_2.4GHz_1
[4/6 10:46:49:886] WI-FI: lwip_scan [700]-> # 10 11 44 -83 BC:9F:E4:54:EB:62  RecordOffice
[4/6 10:46:49:894] WI-FI: lwip_scan [700]-> # 11 11 00 -89 9C:97:26:61:FC:D5  TNCAPE5A34D
[4/6 10:46:49:902] WI-FI: lwip_scan [700]-> # 12  3 44 -73 56:6C:EB:A4:AA:88  DIRECT-BHPET-LPT-MARINAmsXN
[4/6 10:46:49:912] WI-FI: lwip_scan [700]-> # 13  3 46 -52 0C:80:63:BA:AB:E1  aninim Home
[4/6 10:46:49:920] WI-FI: lwip_scan [700]-> # 14  1 44 -75 A6:34:D9:40:86:CF  DIRECT-CCREDDEVILmsRI
[4/6 10:46:49:927] WI-FI: lwip_scan [700]-> # 15  6 44 -79 84:C9:B2:B7:5F:73  Jchil_jc6
[4/6 10:46:49:937] WI-FI: lwip_scan [700]-> # 16  6 44 -90 30:E1:71:D8:2C:9B  DIRECT-9A-HP OfficeJet Pro 8710
[4/6 10:46:49:944] WI-FI: lwip_scan [700]-> # 17  6 44 -86 94:F3:92:76:49:A8  Aquapass
[4/6 10:46:49:955] WI-FI: lwip_scan [700]-> # 18  1 00 -93 CC:DB:93:0D:D8:82  JJGUEST
[4/6 10:46:49:960] CELLULAR: nrf_send_at_cmd [648]-> AT+CEREG=5
[4/6 10:46:49:965] 
[4/6 10:46:49:980] CELLULAR: nrf_check_answer [761]-> OK
[4/6 10:46:49:985] 
[4/6 10:46:49:985] CELLULAR: nrf_send_at_cmd [648]-> AT%XEMPR=1,0,2
[4/6 10:46:49:990] 
[4/6 10:46:50:005] CELLULAR: nrf_check_answer [761]-> OK
[4/6 10:46:50:012] 
[4/6 10:46:50:012] CELLULAR: nrf_send_at_cmd [648]-> AT+CSCON?
[4/6 10:46:50:015] 
[4/6 10:46:50:037] CELLULAR: nrf_check_answer [761]-> +CSCON: 0,0
[4/6 10:46:50:037] 
OK
[4/6 10:46:50:042] 
[4/6 10:46:50:042] CELLULAR: nrf_send_at_cmd [648]-> AT+CSCON=1
[4/6 10:46:50:045] 
[4/6 10:46:50:062] CELLULAR: nrf_check_answer [761]-> OK
[4/6 10:46:50:067] 
[4/6 10:46:50:067] CELLULAR: nrf_send_at_cmd [648]-> AT+CFUN?
[4/6 10:46:50:070] 
[4/6 10:46:50:092] CELLULAR: nrf_check_answer [761]-> +CFUN: 0

[4/6 10:46:50:097] OK
[4/6 10:46:50:097] 
[4/6 10:46:50:097] CELLULAR: nrf_send_at_cmd [648]-> AT+CFUN=1
[4/6 10:46:50:100] 
[4/6 10:46:51:177] CELLULAR: nrf_check_answer [744]-> 
[4/6 10:46:51:177] OK
[4/6 10:46:51:177] 
[4/6 10:46:52:264] CELLULAR: nrf_check_answer [744]-> 
[4/6 10:46:52:264] +CEREG: 2,"198D","00048801",7

[4/6 10:46:52:270] +CSCON: 1
[4/6 10:46:52:270] 
[4/6 10:46:52:577] CELLULAR: nrf_check_answer [761]-> +CEREG: 5,"198D","00048801",7,,,
[4/6 10:46:52:585] CELLULAR: nrf_send_at_cmd [648]-> AT+CEREG?
[4/6 10:46:52:593] 
[4/6 10:46:52:614] CELLULAR: nrf_check_answer [761]-> +CEREG: 5,5,"198D","00048801",7,,,"11100000","11100000"
[4/6 10:46:52:623] 
[4/6 10:46:52:623] OK
[4/6 10:46:52:623] 
[4/6 10:46:52:623] CELLULAR: nrf_send_at_cmd [648]-> AT+COPS?
[4/6 10:46:52:626] 
[4/6 10:46:52:636] CELLULAR: nrf_check_answer [761]-> +COPS: 0,2,"42503",7
[4/6 10:46:52:639] 
[4/6 10:46:52:639] OK
[4/6 10:46:52:639] 
[4/6 10:46:52:645] CELLULAR: nrf_send_at_cmd [648]-> AT%XCBAND
[4/6 10:46:52:650] 
[4/6 10:46:52:665] CELLULAR: nrf_check_answer [761]-> %XCBAND: 3
[4/6 10:46:52:677] 
[4/6 10:46:52:677] OK
[4/6 10:46:52:677] 
[4/6 10:46:52:677] CELLULAR: nrf_send_at_cmd [648]-> AT#XMQTTCON=1,"
[4/6 10:46:52:677] CELLULAR: nrf_send_at_cmd [648]-> PLU39236205
[4/6 10:46:52:686] CELLULAR: nrf_send_at_cmd [648]-> ","","","a34k7wa09ujucc-ats.iot.us-east-1.amazonaws.com",8883,321
[4/6 10:46:52:693] 
[4/6 10:46:55:487] CELLULAR: nrf_check_answer [761]-> #XMQTTEVT: 0,0
[4/6 10:46:55:489] 
[4/6 10:46:55:495] CELLULAR: nrf_send_at_cmd [648]-> AT+CESQ
[4/6 10:46:55:500] 
[4/6 10:46:55:512] CELLULAR: nrf_check_answer [761]-> +CESQ: 99,99,255,255,22,27
[4/6 10:46:55:512] 
[4/6 10:46:55:515] OK
[4/6 10:46:55:515] 
[4/6 10:46:55:538] OUT JSON: data_trans_task [263]-> {"deviceId":"PLU39236205","deviceType":"Collar","requestTime":1717487214,"carrier":"CELLULAR","rssi":-96,"messages":[{"messageId":80,"messageCategory":1,"messageType":11,"recordedTime":1717487214,"firmwareVersion":"collar-fw-1.10.3","bootVersion":"Not_Found","nrfModemVer":"nrf9160_1.3.5","nrfSdkVer":"2.5.2","attachmentStatus":"Off-Pet","battery":13,"volt":3.849,"band":3,"provider":"42503","chargeStatus":"Charging","collarStatus":"ON","operationMode":2,"debugMode":6,"sosMode":0},{"messageId":81,"messageCategory":1,"messageType":9,"recordedTime":1717487149,"fragmentNumber":75,"packetCurrent":1,"message":"AT+CESQ  "},{"messageId":82,"messageCategory":1,"messageType":9,"recordedTime":1717487149,"fragmentNumber":76,"packetCurrent":1,"message":"+CESQ: 99,99,255,255,20,30    OK  "},{"messageId":83,"messageCategory":1,"messageType":9,"recordedTime":1717487149,"fragmentNumber":77,"packetCurrent":1,"message":"AT#XMQTTPUB= "},{"messageId":84,"messageCategory":1,"messageType":9,"recordedTime":1717487149,"fragmentNumber":78,"packetCurrent":1,"message":"Staging/ToServer/v2/Operational"},{"messageId":85,"messageCategory":1,"messageType":9,"recordedTime":1717487149,"fragmentNumber":79,"packetCurrent":1,"message":" ,  ,1  "},{"messageId":86,"messageCategory":1,"messageType":9,"recordedTime":1717487149,"fragmentNumber":80,"packetCurrent":1,"message":"OK  "},{"messageId":87,"messageCategory":1,"messageType":9,"recordedTime":1717487149,"fragmentNumber":81,"packetCurrent":1,"message":"+++"},{"messageId":88,"messageCategory":1,"messageType":9,"recordedTime":1717487149,"fragmentNumber":82,"packetCurrent":1,"message":"#XDATAMODE: 0  "},{"messageId":89,"messageCategory":1,"messageType":9,"recordedTime":1717487149,"fragmentNumber":83,"packetCurrent":1,"message":"#XMQTTEVT: 3,0  "},{"messageId":90,"messageCategory":1,"messageType":9,"recordedTime":1717487150,"fragmentNumber":84,"packetCurrent":1,"message":"AT+CESQ  "}]}
[4/6 10:46:55:710] CELLULAR: nrf_send_at_cmd [648]-> AT+CESQ
[4/6 10:46:55:715] 
[4/6 10:46:55:727] CELLULAR: nrf_check_answer [761]-> +CESQ: 99,99,255,255,22,27
[4/6 10:46:55:729] 
[4/6 10:46:55:729] OK
[4/6 10:46:55:729] 
CELLULAR: nrf_send_at_cmd [648]-> AT#XMQTTPUB="
[4/6 10:46:55:746] CELLULAR: nrf_send_at_cmd [648]-> Staging/ToServer/v2/Operational
[4/6 10:46:55:759] CELLULAR: nrf_send_at_cmd [648]-> ","",1
[4/6 10:46:55:765] 
[4/6 10:46:55:775] CELLULAR: nrf_check_answer [761]-> OK
[4/6 10:46:55:780] 
[4/6 10:46:56:134] CELLULAR: nrf_send_at_cmd [648]-> +++
[4/6 10:46:56:166] CELLULAR ERROR: nrf_check_answer [774]-> NULL
[4/6 10:46:56:171] CELLULAR ERROR: nrf_send_data_to_aws [943]-> There is a problem with sending data. Execution of the AT ping command
[4/6 10:46:56:443] CELLULAR: nrf_send_at_cmd [648]-> AT
[4/6 10:46:56:443] 
[4/6 10:46:57:260] CELLULAR: nrf_check_answer [744]-> 
[4/6 10:46:57:260] #XMQTTEVT: 3,0
[4/6 10:46:57:265] 
[4/6 10:46:57:265] CELLULAR ERROR: nrf_check_answer [774]-> 
#XMQTTEVT: 3,0
[4/6 10:46:57:272] 
[4/6 10:46:57:272] CELLULAR ERROR: nrf_exe_at_ping_cmd [2843]-> nRF modem not responding, the "hard" reset is required
[4/6 10:46:57:280] DATA_TRANS ERROR: data_trans_task [281]-> Packet fail
[4/6 10:46:58:286] CELLULAR: nrf_reset [1454]-> Reset nRF
[4/6 10:46:58:568] CELLULAR: nrf_wake_up [1279]-> Wake Up nRF
[4/6 10:46:59:578] CELLULAR: nrf_check_answer [744]-> 
[4/6 10:46:59:578] #XMQTTEVT: 3,0
[4/6 10:46:59:580] 
[4/6 10:46:59:926] CELLULAR: nrf_check_answer [761]-> Ready
[4/6 10:46:59:928] 
[4/6 10:46:59:928] CELLULAR: nrf_send_at_cmd [648]-> AT
[4/6 10:46:59:929] 
[4/6 10:46:59:930] CELLULAR: nrf_check_answer [761]-> OK
[4/6 10:46:59:930] 
[4/6 10:46:59:930] CELLULAR: nrf_send_at_cmd [648]-> AT%SHORTSWVER
[4/6 10:46:59:932] 
[4/6 10:46:59:932] CELLULAR: nrf_check_answer [761]-> %SHORTSWVER: nrf9160_1.3.5
[4/6 10:46:59:933] 
[4/6 10:46:59:933] OK
[4/6 10:46:59:933] 
[4/6 10:46:59:933] CELLULAR: nrf_send_at_cmd [648]-> AT#XSLMVER
[4/6 10:46:59:934] 
[4/6 10:46:59:935] CELLULAR: nrf_check_answer [761]-> #XSLMVER: "2.5.2","2.5.0-lte-5ccd2d4dd54c"
[4/6 10:46:59:937] 
[4/6 10:46:59:937] OK
[4/6 10:46:59:937] 
[4/6 10:46:59:937] CELLULAR: nrf_send_at_cmd [648]-> AT#XUUID
[4/6 10:46:59:937] 
[4/6 10:46:59:938] CELLULAR: nrf_check_answer [761]-> #XUUID: 50475934-3538-45e4-8027-160d4c0f9789

[4/6 10:46:59:939] OK
[4/6 10:46:59:939] 
[4/6 10:46:59:939] CELLULAR: nrf_send_at_cmd [648]-> AT#XGPIOCFG=1,19
[4/6 10:46:59:940] 
[4/6 10:46:59:941] CELLULAR: nrf_check_answer [761]-> OK
[4/6 10:46:59:941] 
[4/6 10:46:59:941] CELLULAR: nrf_send_at_cmd [648]-> AT#XGPIO=0,19,0
[4/6 10:46:59:942] 
[4/6 10:46:59:943] CELLULAR: nrf_check_answer [761]-> OK
[4/6 10:46:59:944] 
[4/6 10:46:59:944] CELLULAR: nrf_send_at_cmd [648]-> AT%XSYSTEMMODE=1,0,1,0
[4/6 10:46:59:944] 
[4/6 10:46:59:945] CELLULAR: nrf_check_answer [761]-> OK
[4/6 10:46:59:946] 
[4/6 10:46:59:946] CELLULAR: nrf_send_at_cmd [648]-> AT%XCOEX0=1,1,1565,1586
[4/6 10:46:59:950] 
[4/6 10:46:59:960] CELLULAR: nrf_check_answer [761]-> OK
[4/6 10:46:59:965] 
[4/6 10:46:59:965] CELLULAR: nrf_send_at_cmd [648]-> AT%XBANDLOCK=1,"
[4/6 10:46:59:972] CELLULAR: nrf_send_at_cmd [648]-> 10000001100010001110
[4/6 10:46:59:983] CELLULAR: nrf_send_at_cmd [648]-> "
[4/6 10:46:59:983] 
[4/6 10:47:00:004] CELLULAR: nrf_check_answer [761]-> OK
[4/6 10:47:00:005] 
[4/6 10:47:00:005] CELLULAR: nrf_send_at_cmd [648]-> AT+CEMODE=0
[4/6 10:47:00:006] 
[4/6 10:47:00:020] CELLULAR: nrf_check_answer [761]-> OK
[4/6 10:47:00:025] 
[4/6 10:47:00:423] CELLULAR: nrf_set_aws_certificates [1978]-> The AWS certificates already exist
[4/6 10:47:00:426] CELLULAR: nrf_send_at_cmd [648]-> AT%CMNG=1,16842753,0
[4/6 10:47:00:427] 
[4/6 10:47:00:436] CELLULAR: nrf_check_answer [761]-> %CMNG: 16842753,0,"AD6FB002E6B34C0559FA8F93A3794FF12C4E3F119BD77290C52525123FB9EA74"
[4/6 10:47:00:437] 
[4/6 10:47:00:437] OK
[4/6 10:47:00:437] 
[4/6 10:47:00:438] CELLULAR: nrf_send_at_cmd [648]-> AT%CMNG=1,16842753,1
[4/6 10:47:00:440] 
[4/6 10:47:00:447] CELLULAR: nrf_check_answer [761]-> %CMNG: 16842753,1,"4ECD42F25D80343F74409A881615C5A969AFB546D82EC447E904F241A3351812"
[4/6 10:47:00:449] 
[4/6 10:47:00:449] OK
[4/6 10:47:00:449] 
[4/6 10:47:00:450] CELLULAR: nrf_send_at_cmd [648]-> AT%CMNG=1,16842753,2
[4/6 10:47:00:452] 
[4/6 10:47:00:461] CELLULAR: nrf_check_answer [761]-> %CMNG: 16842753,2,"287D03C47C8F2662E128316EBA1EFB974280FA3C8D74910D90DE8C365C27B70A"
[4/6 10:47:00:465] 
[4/6 10:47:00:465] OK
[4/6 10:47:00:465] 
[4/6 10:47:00:487] CELLULAR: nrf_send_at_cmd [648]-> AT+CPSMS=0
[4/6 10:47:00:490] 
[4/6 10:47:00:518] CELLULAR: nrf_check_answer [761]-> OK
[4/6 10:47:00:519] 
[4/6 10:47:00:519] CELLULAR: nrf_get_coordinates [2998]-> The "location_measure_flag" flag: 1, the "get_gps_flag" flag: 1
[4/6 10:47:02:589] WI-FI: lwip_scan [700]-> #  1  1 44 -71 A0:4F:D4:3E:41:E1  Cellcom-WiFi_4837
[4/6 10:47:02:596] WI-FI: lwip_scan [700]-> #  2  1 44 -46 E8:1C:BA:2C:2A:10  Petpace
[4/6 10:47:02:606] WI-FI: lwip_scan [700]-> #  3  1 44 -76 A6:34:D9:40:86:CF  DIRECT-CCREDDEVILmsRI
[4/6 10:47:02:611] WI-FI: lwip_scan [700]-> #  4  6 44 -79 84:C9:B2:B7:5F:73  Jchil_jc6
WI-FI: lwip_scan [700]-> #  5  6 00 -77 4A:81:D4:8D:A0:9B  ToysShefaim
[4/6 10:47:02:629] WI-FI: lwip_scan [700]-> #  6  6 84 -77 90:77:EE:DF:83:C0  LMDWWIFI
[4/6 10:47:02:634] WI-FI: lwip_scan [700]-> #  7  6 00 -77 90:77:EE:DF:83:C2  JJGUEST
[4/6 10:47:02:644] WI-FI: lwip_scan [700]-> #  8  6 84 -77 90:77:EE:DF:83:C3  JJWIFI
[4/6 10:47:02:646] WI-FI: lwip_scan [700]-> #  9  6 00 -77 90:77:EE:DF:83:C5  JJINTERNET
[4/6 10:47:02:659] WI-FI: lwip_scan [700]-> # 10  6 44 -89 94:F3:92:76:49:A8  Aquapass
[4/6 10:47:02:661] WI-FI: lwip_scan [700]-> # 11 11 00 -89 AC:F1:DF:18:58:A5  BezeqNGN_003567_2.4GHz_1
[4/6 10:47:02:671] WI-FI: lwip_scan [700]-> # 12 11 44 -82 BC:9F:E4:54:EB:62  RecordOffice
[4/6 10:47:02:682] WI-FI: lwip_scan [700]-> # 13  3 44 -73 56:6C:EB:A4:AA:88  DIRECT-BHPET-LPT-MARINAmsXN
[4/6 10:47:02:686] WI-FI: lwip_scan [700]-> # 14  3 46 -52 0C:80:63:BA:AB:E1  aninim Home
[4/6 10:47:02:696] WI-FI: lwip_scan [700]-> # 15  1 44 -82 60:32:B1:43:90:AF  Triple C 2.4
[4/6 10:47:02:701] WI-FI: lwip_scan [700]-> # 16  1 44 -94 A0:4F:D4:43:87:52  
CELLULAR: nrf_send_at_cmd [648]-> AT+CEREG=5
[4/6 10:47:02:719] 
[4/6 10:47:02:730] CELLULAR: nrf_check_answer [761]-> OK
[4/6 10:47:02:737] 
[4/6 10:47:02:737] CELLULAR: nrf_send_at_cmd [648]-> AT%XEMPR=1,0,2
[4/6 10:47:02:739] 
[4/6 10:47:02:756] CELLULAR: nrf_check_answer [761]-> OK
[4/6 10:47:02:764] 
[4/6 10:47:02:764] CELLULAR: nrf_send_at_cmd [648]-> AT+CSCON?
[4/6 10:47:02:764] 
[4/6 10:47:02:786] CELLULAR: nrf_check_answer [761]-> +CSCON: 0,0
[4/6 10:47:02:793] 
[4/6 10:47:02:793] OK
[4/6 10:47:02:793] 
[4/6 10:47:02:795] CELLULAR: nrf_send_at_cmd [648]-> AT+CSCON=1
[4/6 10:47:02:800] 
[4/6 10:47:02:816] CELLULAR: nrf_check_answer [761]-> OK
[4/6 10:47:02:816] 
[4/6 10:47:02:819] CELLULAR: nrf_send_at_cmd [648]-> AT+CFUN?
[4/6 10:47:02:819] 
[4/6 10:47:02:846] CELLULAR: nrf_check_answer [761]-> +CFUN: 0
[4/6 10:47:02:846] 
[4/6 10:47:02:846] OK
[4/6 10:47:02:846] 
[4/6 10:47:02:849] CELLULAR: nrf_send_at_cmd [648]-> AT+CFUN=1
[4/6 10:47:02:854] 
[4/6 10:47:03:927] CELLULAR: nrf_check_answer [744]-> 
[4/6 10:47:03:931] OK
[4/6 10:47:03:931] 
[4/6 10:47:05:015] CELLULAR: nrf_check_answer [744]-> 
[4/6 10:47:05:017] +CEREG: 2,"198D","00048801",7
[4/6 10:47:05:019] 
[4/6 10:47:05:019] +CSCON: 1
[4/6 10:47:05:019] 
[4/6 10:47:05:493] CELLULAR: nrf_check_answer [761]-> +CEREG: 5,"198D","00048801",7,,
[4/6 10:47:05:493] CELLULAR: nrf_send_at_cmd [648]-> AT+CEREG?
[4/6 10:47:05:494] 
[4/6 10:47:05:497] CELLULAR: nrf_check_answer [761]-> +CEREG: 5,5,"198D","00048801",7,,,"11100000","11100000"
[4/6 10:47:05:506] 
[4/6 10:47:05:506] OK
[4/6 10:47:05:506] 
[4/6 10:47:05:506] CELLULAR: nrf_send_at_cmd [648]-> AT+COPS?
[4/6 10:47:05:509] 
[4/6 10:47:05:527] CELLULAR: nrf_check_answer [761]-> +COPS: 0,2,"42503",7
[4/6 10:47:05:534] 
[4/6 10:47:05:534] OK
[4/6 10:47:05:534] 
[4/6 10:47:05:534] CELLULAR: nrf_send_at_cmd [648]-> AT%XCBAND
[4/6 10:47:05:539] 
[4/6 10:47:05:557] CELLULAR: nrf_check_answer [761]-> %XCBAND: 3
[4/6 10:47:05:559] 
[4/6 10:47:05:559] OK
[4/6 10:47:05:559] 
[4/6 10:47:05:563] CELLULAR: nrf_send_at_cmd [648]-> AT#XMQTTCON=1,"
[4/6 10:47:05:569] CELLULAR: nrf_send_at_cmd [648]-> PLU39236205
[4/6 10:47:05:576] CELLULAR: nrf_send_at_cmd [648]-> ","","","a34k7wa09ujucc-ats.iot.us-east-1.amazonaws.com",8883,321
[4/6 10:47:05:585] 

[dejans]

Hi,

Thank you for providing the log. We will look into it internally. I will get back to you by the end of the next week at the latest.

Best regards,
Dejan

[dejans]

Hi,

Could you please provide similar log to what you have already done and RTT traces (have a look at modem tracing with RTT) with Kconfig option CONFIG_SLM_LOG_LEVEL_DBG=y enabled?

Best regards,
Dejan

[Dmitry Krol]

Hi.

In order to compile it, I need to add "zephyr,console = &uart2;" to overlay, but it says compile error: "malformed value"

/ {
     chosen {
         ncs,slm-uart = &uart2;
         zephyr,console = &uart2;
     };
};

devicetree error: C:/ncs/v2.5.2/nrf/applications/serial_lte_modem/boards/nrf9160dk_nrf9160_ns.overlay:10 (column 19): parse error: malformed value

[Dmitry Krol]

We found the problem in our side (We sent "+++" inside the trace)

[dejans]

Hi,

It is great to hear that you found the source of the problem. Thank you for the update.

Best regards,
Dejan