This post is older than 2 years and might not be relevant anymore
More Info: Consider searching for newer posts

Serial LTE modem looses some unsolicited messages after early wakeup and need AT%XFACTORYRESET=0 to recover

Michal Mühlpachr over 4 years ago

NCS v1.6.0

CONFIG_SLM_GPIO_WAKEUP=y

CONFIG_SLM_INTERFACE_PIN=24

When Serial LTE modem is woken up after #XSLEEP=2 without 2s delay, it starts to loose some unsolicited messages.

RESET do not recover.

E.g.

RESET by RESET pin

AT+CEREG=5

AT+CFUN=1

we get `+CEREG: 2` but not `+CEREG: 5` (as usual) but modem reports by `+CEREG?` 5

Parents

0 Didrik Rokhaug over 4 years ago

Hi,

Do you have a log you can share that shows the problem?

Best regards,

Didrik
Cancel
Vote Up 0 Vote Down

Sign in to reply

Verify Answer

Cancel

0 Michal Mühlpachr over 4 years ago in reply to Didrik Rokhaug

Without 2s delay after #XSLEEP

 [00:01:02.699,859] <dbg> hio_lte_uart: hio_lte_uart_send: CMD: AT%HWVERSION
ⓘ  [00:01:02.726,104] <dbg> hio_lte_uart: process_rx_char: URC: %HWVERSION: nRF9160 SICA A00
ⓘ  [00:01:02.726,623] <dbg> hio_lte_uart: process_rx_char: RSP: OK
ⓘ  [00:01:02.827,178] <dbg> hio_lte_uart: hio_lte_uart_send: CMD: AT%SHORTSWVER
ⓘ  [00:01:02.854,980] <dbg> hio_lte_uart: process_rx_char: URC: %SHORTSWVER: nrf9160_1.3.0
ⓘ  [00:01:02.855,499] <dbg> hio_lte_uart: process_rx_char: RSP: OK
ⓘ  [00:01:02.956,054] <dbg> hio_lte_uart: hio_lte_uart_send: CMD: AT%XPOFWARN=1,30
ⓘ  [00:01:02.979,766] <dbg> hio_lte_uart: process_rx_char: RSP: OK
ⓘ  [00:01:03.080,322] <dbg> hio_lte_uart: hio_lte_uart_send: CMD: AT%XSYSTEMMODE=0,1,0,0
ⓘ  [00:01:03.106,689] <dbg> hio_lte_uart: process_rx_char: RSP: OK
ⓘ  [00:01:03.207,244] <dbg> hio_lte_uart: hio_lte_uart_send: CMD: AT%REL14FEAT=1,1,1,1,0
ⓘ  [00:01:03.231,536] <dbg> hio_lte_uart: process_rx_char: RSP: OK
ⓘ  [00:01:03.332,092] <dbg> hio_lte_uart: hio_lte_uart_send: CMD: AT%XDATAPRFL=0
ⓘ  [00:01:03.356,445] <dbg> hio_lte_uart: process_rx_char: RSP: OK
ⓘ  [00:01:03.456,970] <dbg> hio_lte_uart: hio_lte_uart_send: CMD: AT%XSIM=1
ⓘ  [00:01:03.481,292] <dbg> hio_lte_uart: process_rx_char: RSP: OK
ⓘ  [00:01:03.581,848] <dbg> hio_lte_uart: hio_lte_uart_send: CMD: AT%XNETTIME=1
ⓘ  [00:01:03.606,170] <dbg> hio_lte_uart: process_rx_char: RSP: OK
ⓘ  [00:01:03.706,695] <dbg> hio_lte_uart: hio_lte_uart_send: CMD: AT%XTIME=1
ⓘ  [00:01:03.731,048] <dbg> hio_lte_uart: process_rx_char: RSP: OK
ⓘ  [00:01:03.831,573] <dbg> hio_lte_uart: hio_lte_uart_send: CMD: AT+CEREG=5
ⓘ  [00:01:03.855,926] <dbg> hio_lte_uart: process_rx_char: RSP: OK
ⓘ  [00:01:03.956,451] <dbg> hio_lte_uart: hio_lte_uart_send: CMD: AT+CGEREP=1
ⓘ  [00:01:03.980,804] <dbg> hio_lte_uart: process_rx_char: RSP: OK
ⓘ  [00:01:04.081,329] <dbg> hio_lte_uart: hio_lte_uart_send: CMD: AT+CSCON=1
ⓘ  [00:01:04.105,682] <dbg> hio_lte_uart: process_rx_char: RSP: OK
ⓘ  [00:01:04.206,237] <dbg> hio_lte_uart: hio_lte_uart_send: CMD: AT+CPSMS=1,,,"00111000","00000000"
ⓘ  [00:01:04.232,574] <dbg> hio_lte_uart: process_rx_char: RSP: OK
ⓘ  [00:01:04.333,099] <dbg> hio_lte_uart: hio_lte_uart_send: CMD: AT+CNEC=24
ⓘ  [00:01:04.357,482] <dbg> hio_lte_uart: process_rx_char: RSP: OK
ⓘ  [00:01:04.458,007] <dbg> hio_lte_uart: hio_lte_uart_send: CMD: AT+CMEE=1
ⓘ  [00:01:04.482,330] <dbg> hio_lte_uart: process_rx_char: RSP: OK
ⓘ  [00:01:04.582,855] <dbg> hio_lte_uart: hio_lte_uart_send: CMD: AT+CEPPI=1
ⓘ  [00:01:04.607,208] <dbg> hio_lte_uart: process_rx_char: RSP: OK
ⓘ  [00:01:04.707,733] <dbg> hio_lte_uart: hio_lte_uart_send: CMD: AT+CFUN=1
ⓘ  [00:01:04.754,272] <dbg> hio_lte_uart: process_rx_char: RSP: OK
ⓘ  [00:01:05.668,731] <dbg> hio_lte_uart: process_rx_char: URC: %XSIM: 1
ⓘ  [00:01:05.854,858] <dbg> hio_lte_uart: hio_lte_uart_send: CMD: AT+CIMI
ⓘ  [00:01:05.872,131] <dbg> hio_lte_uart: process_rx_char: RSP: 901288003957931
ⓘ  [00:01:05.872,680] <dbg> hio_lte_uart: process_rx_char: RSP: OK
ⓘ  [00:01:20.853,546] <dbg> hio_lte_uart: process_rx_char: URC: +CEREG: 2,"BBA0","0007361F",9
ⓘ  [00:01:20.854,064] <dbg> hio_lte_talk: hio_lte_talk_cereg: Parsed +CEREG
ⓘ  [00:01:21.510,101] <dbg> hio_lte_uart: process_rx_char: URC: +CSCON: 1
ⓘ  [00:01:22.526,824] <dbg> hio_lte_uart: process_rx_char: URC: +CGEV: ME PDN ACT 0,0
ⓘ  [00:01:22.527,374] <dbg> hio_lte_uart: process_rx_char: URC: +CNEC_ESM: 50,0
ⓘ  [00:01:22.528,045] <dbg> hio_lte_uart: process_rx_char: URC: +CEREG: 5,"BBA0","0007361F",9,,,"00000000","00111000"
ⓘ  [00:01:22.528,656] <dbg> hio_lte_talk: hio_lte_talk_cereg: Parsed +CEREG
ⓘ  [00:01:22.629,272] <dbg> hio_lte_uart: hio_lte_uart_send: CMD: AT#XSOCKET=1,2,0
ⓘ  [00:01:22.647,674] <dbg> hio_lte_uart: process_rx_char: RSP: #XSOCKET: 1,2,0,17
ⓘ  [00:01:22.648,193] <dbg> hio_lte_uart: process_rx_char: RSP: OK
ⓘ  [00:01:22.648,712] <inf> hio_net_lte: Sleep initiated [0x20000680]
ⓘ  [00:01:22.742,401] <dbg> hio_lte_uart: process_rx_char: URC: %XTIME: ,"12706211048380","01"
ⓘ  [00:01:22.749,206] <dbg> hio_lte_uart: hio_lte_uart_send: CMD: AT#XSLEEP=2
ⓘ  [00:01:22.750,793] <inf> hio_net_lte: Attach succeeded [0x20000680]
ⓘ  [00:01:22.751,220] <inf> test_lte: Event `HIO_NET_LTE_EVENT_ATTACH_DONE`
ⓘ  [00:01:22.751,678] <inf> hio_net_lte: Dequeued message to send (port 7777, len 4) [0x20000680]
ⓘ  [00:01:22.752,227] <inf> hio_net_lte: Send started [0x20000680]
ⓘ  [00:01:22.752,807] <inf> hio_net_lte: Wake up initiated [0x20000680]
ⓘ  [00:01:23.453,582] <dbg> test_lte: test_lte: Alive
ⓘ  [00:01:23.863,525] <dbg> hio_lte_uart: hio_lte_uart_send: CMD: AT#XSENDTO="192.168.168.1",7777,0,"01020304"
ⓘ  [00:01:28.868,225] <err> hio_lte_uart: Call `hio_sys_msgq_get` failed
ⓘ  [00:01:28.868,621] <err> hio_lte_talk: Call `hio_lte_uart_recv` failed
ⓘ  [00:01:28.869,018] <err> hio_net_lte: Call `hio_lte_talk_rsp` failed [0x20000680]
ⓘ  [00:01:28.869,537] <wrn> hio_net_lte: Send failed [0x20000680]
ⓘ  [00:01:28.869,934] <inf> test_lte: Event `HIO_NET_LTE_EVENT_SEND_ERROR`
ⓘ  [00:01:28.870,361] <inf> hio_net_lte: Attach started [0x20000680]
ⓘ  [00:01:29.880,950] <inf> hio_net_lte: Wake up initiated [0x20000680]
ⓘ  [00:01:30.991,607] <dbg> hio_lte_uart: hio_lte_uart_send: CMD: AT%HWVERSION
ⓘ  [00:01:35.993,377] <err> hio_lte_uart: Call `hio_sys_msgq_get` failed
ⓘ  [00:01:35.993,774] <err> hio_lte_talk: Call `hio_lte_uart_recv` failed
ⓘ  [00:01:35.994,201] <err> hio_net_lte: Call `hio_lte_talk_cmd_ok` failed [0x20000680]
ⓘ  [00:01:35.994,659] <wrn> hio_net_lte: Attach failed [0x20000680]
ⓘ  [00:01:35.995,086] <inf> test_lte: Event `HIO_NET_LTE_EVENT_ATTACH_ERROR`
ⓘ  [00:02:07.005,706] <inf> hio_net_lte: Wake up initiated [0x20000680]
ⓘ  [00:02:08.116,363] <dbg> hio_lte_uart: hio_lte_uart_send: CMD: AT%HWVERSION
ⓘ  [00:02:08.143,554] <dbg> hio_lte_uart: process_rx_char: URC: %HWVERSION: nRF9160 SICA A00
ⓘ  [00:02:08.144,073] <dbg> hio_lte_uart: process_rx_char: RSP: OK
ⓘ  [00:02:08.244,659] <dbg> hio_lte_uart: hio_lte_uart_send: CMD: AT%SHORTSWVER
ⓘ  [00:02:08.272,460] <dbg> hio_lte_uart: process_rx_char: URC: %SHORTSWVER: nrf9160_1.3.0
ⓘ  [00:02:08.272,979] <dbg> hio_lte_uart: process_rx_char: RSP: OK
ⓘ  [00:02:08.373,565] <dbg> hio_lte_uart: hio_lte_uart_send: CMD: AT%XPOFWARN=1,30
ⓘ  [00:02:08.399,261] <dbg> hio_lte_uart: process_rx_char: RSP: OK
ⓘ  [00:02:08.499,908] <dbg> hio_lte_uart: hio_lte_uart_send: CMD: AT%XSYSTEMMODE=0,1,0,0
ⓘ  [00:02:08.526,184] <dbg> hio_lte_uart: process_rx_char: RSP: OK
ⓘ  [00:02:08.626,739] <dbg> hio_lte_uart: hio_lte_uart_send: CMD: AT%REL14FEAT=1,1,1,1,0
ⓘ  [00:02:08.652,496] <dbg> hio_lte_uart: process_rx_char: RSP: OK
ⓘ  [00:02:08.753,082] <dbg> hio_lte_uart: hio_lte_uart_send: CMD: AT%XDATAPRFL=0
ⓘ  [00:02:08.777,435] <dbg> hio_lte_uart: process_rx_char: RSP: OK
ⓘ  [00:02:08.877,960] <dbg> hio_lte_uart: hio_lte_uart_send: CMD: AT%XSIM=1
ⓘ  [00:02:08.902,282] <dbg> hio_lte_uart: process_rx_char: RSP: OK
ⓘ  [00:02:09.002,838] <dbg> hio_lte_uart: hio_lte_uart_send: CMD: AT%XNETTIME=1
ⓘ  [00:02:09.027,191] <dbg> hio_lte_uart: process_rx_char: RSP: OK
ⓘ  [00:02:09.127,716] <dbg> hio_lte_uart: hio_lte_uart_send: CMD: AT%XTIME=1
ⓘ  [00:02:09.152,038] <dbg> hio_lte_uart: process_rx_char: RSP: OK
ⓘ  [00:02:09.252,563] <dbg> hio_lte_uart: hio_lte_uart_send: CMD: AT+CEREG=5
ⓘ  [00:02:09.276,947] <dbg> hio_lte_uart: process_rx_char: RSP: OK
ⓘ  [00:02:09.377,471] <dbg> hio_lte_uart: hio_lte_uart_send: CMD: AT+CGEREP=1
ⓘ  [00:02:09.401,824] <dbg> hio_lte_uart: process_rx_char: RSP: OK
ⓘ  [00:02:09.502,410] <dbg> hio_lte_uart: hio_lte_uart_send: CMD: AT+CSCON=1
ⓘ  [00:02:09.526,702] <dbg> hio_lte_uart: process_rx_char: RSP: OK
ⓘ  [00:02:09.627,258] <dbg> hio_lte_uart: hio_lte_uart_send: CMD: AT+CPSMS=1,,,"00111000","00000000"
ⓘ  [00:02:09.653,564] <dbg> hio_lte_uart: process_rx_char: RSP: OK
ⓘ  [00:02:09.754,089] <dbg> hio_lte_uart: hio_lte_uart_send: CMD: AT+CNEC=24
ⓘ  [00:02:09.778,472] <dbg> hio_lte_uart: process_rx_char: RSP: OK
ⓘ  [00:02:09.878,997] <dbg> hio_lte_uart: hio_lte_uart_send: CMD: AT+CMEE=1
ⓘ  [00:02:09.903,320] <dbg> hio_lte_uart: process_rx_char: RSP: OK
ⓘ  [00:02:10.003,906] <dbg> hio_lte_uart: hio_lte_uart_send: CMD: AT+CEPPI=1
ⓘ  [00:02:10.028,228] <dbg> hio_lte_uart: process_rx_char: RSP: OK
ⓘ  [00:02:10.128,753] <dbg> hio_lte_uart: hio_lte_uart_send: CMD: AT+CFUN=1
ⓘ  [00:02:10.175,231] <dbg> hio_lte_uart: process_rx_char: RSP: OK
ⓘ  [00:02:11.089,721] <dbg> hio_lte_uart: process_rx_char: URC: %XSIM: 1
ⓘ  [00:02:11.275,817] <dbg> hio_lte_uart: hio_lte_uart_send: CMD: AT+CIMI
ⓘ  [00:02:11.293,182] <dbg> hio_lte_uart: process_rx_char: RSP: 901288003957931
ⓘ  [00:02:11.293,670] <dbg> hio_lte_uart: process_rx_char: RSP: OK
ⓘ  [00:02:11.394,256] <dbg> hio_lte_uart: hio_lte_uart_send: CMD: AT#XSOCKET=1,2,0
ⓘ  [00:02:11.411,987] <dbg> hio_lte_uart: process_rx_char: RSP: #XSOCKET: 1,2,0,17
ⓘ  [00:02:11.412,506] <dbg> hio_lte_uart: process_rx_char: RSP: OK
ⓘ  [00:02:11.412,994] <inf> hio_net_lte: Sleep initiated [0x20000680]
ⓘ  [00:02:11.513,488] <dbg> hio_lte_uart: hio_lte_uart_send: CMD: AT#XSLEEP=2
ⓘ  [00:02:11.515,075] <inf> hio_net_lte: Attach succeeded [0x20000680]
ⓘ  [00:02:11.515,502] <inf> test_lte: Event `HIO_NET_LTE_EVENT_ATTACH_DONE`
ⓘ  [00:02:21.516,082] <inf> hio_net_lte: Wake up initiated [0x20000680]
ⓘ  [00:02:22.626,831] <dbg> hio_lte_uart: hio_lte_uart_send: CMD: AT#XSENDTO="192.168.168.1",7777,0,"01020304"
ⓘ  [00:02:22.649,017] <dbg> hio_lte_uart: process_rx_char: RSP: #XSOCKET: -114,"closed"
ⓘ  [00:02:22.649,566] <dbg> hio_lte_uart: process_rx_char: RSP: #XSENDTO: 0
ⓘ  [00:02:22.650,085] <dbg> hio_lte_uart: process_rx_char: RSP: ERROR
ⓘ  [00:02:22.650,543] <err> hio_net_lte: Call `hio_lte_tok_pfx` failed [0x20000680]
ⓘ  [00:02:22.651,062] <wrn> hio_net_lte: Send failed [0x20000680]
ⓘ  [00:02:22.651,428] <inf> test_lte: Event `HIO_NET_LTE_EVENT_SEND_ERROR`
ⓘ  [00:02:22.651,824] <inf> hio_net_lte: Attach started [0x20000680]
ⓘ  [00:02:23.454,010] <dbg> test_lte: test_lte: Alive
ⓘ  [00:02:23.662,414] <inf> hio_net_lte: Wake up initiated [0x20000680]
ⓘ  [00:02:24.773,071] <dbg> hio_lte_uart: hio_lte_uart_send: CMD: AT%HWVERSION
ⓘ  [00:02:24.774,749] <err> hio_lte_talk: Response mismatch
ⓘ  [00:02:24.775,115] <err> hio_net_lte: Call `hio_lte_talk_cmd_ok` failed [0x20000680]
ⓘ  [00:02:24.775,604] <wrn> hio_net_lte: Attach failed [0x20000680]
ⓘ  [00:02:24.776,062] <inf> test_lte: Event `HIO_NET_LTE_EVENT_ATTACH_ERROR`
ⓘ  [00:02:55.786,651] <inf> hio_net_lte: Wake up initiated [0x20000680]
ⓘ  [00:02:56.897,308] <dbg> hio_lte_uart: hio_lte_uart_send: CMD: AT%HWVERSION
ⓘ  [00:02:56.899,017] <err> hio_lte_talk: Response mismatch
ⓘ  [00:02:56.899,383] <err> hio_net_lte: Call `hio_lte_talk_cmd_ok` failed [0x20000680]
ⓘ  [00:02:56.899,902] <wrn> hio_net_lte: Attach failed [0x20000680]
ⓘ  [00:02:56.900,299] <inf> test_lte: Event `HIO_NET_LTE_EVENT_ATTACH_ERROR`
ⓘ  [00:02:56.924,011] <dbg> hio_lte_uart: process_rx_char: URC: %HWVERSION: nRF9160 SICA A00
ⓘ  [00:02:56.924,530] <dbg> hio_lte_uart: process_rx_char: RSP: OK
ⓘ  [00:03:23.454,437] <dbg> test_lte: test_lte: Alive
ⓘ  [00:03:27.910,980] <inf> hio_net_lte: Wake up initiated [0x20000680]
ⓘ  [00:03:29.021,636] <dbg> hio_lte_uart: hio_lte_uart_send: CMD: AT%HWVERSION
ⓘ  [00:03:29.123,443] <dbg> hio_lte_uart: hio_lte_uart_send: CMD: AT%SHORTSWVER
ⓘ  [00:03:34.125,274] <err> hio_lte_uart: Call `hio_sys_msgq_get` failed
ⓘ  [00:03:34.125,701] <err> hio_lte_talk: Call `hio_lte_uart_recv` failed
ⓘ  [00:03:34.126,098] <err> hio_net_lte: Call `hio_lte_talk_cmd_ok` failed [0x20000680]
ⓘ  [00:03:34.126,586] <wrn> hio_net_lte: Attach failed [0x20000680]
ⓘ  [00:03:34.127,044] <inf> test_lte: Event `HIO_NET_LTE_EVENT_ATTACH_ERROR`

Then after RESET

 [00:00:37.684,906] <dbg> hio_lte_uart: hio_lte_uart_send: CMD: AT%HWVERSION
ⓘ  [00:00:37.710,937] <dbg> hio_lte_uart: process_rx_char: URC: %HWVERSION: nRF9160 SICA A00
ⓘ  [00:00:37.711,456] <dbg> hio_lte_uart: process_rx_char: RSP: OK
ⓘ  [00:00:37.812,042] <dbg> hio_lte_uart: hio_lte_uart_send: CMD: AT%SHORTSWVER
ⓘ  [00:00:37.837,799] <dbg> hio_lte_uart: process_rx_char: URC: %SHORTSWVER: nrf9160_1.3.0
ⓘ  [00:00:37.838,317] <dbg> hio_lte_uart: process_rx_char: RSP: OK
ⓘ  [00:00:37.938,873] <dbg> hio_lte_uart: hio_lte_uart_send: CMD: AT%XPOFWARN=1,30
ⓘ  [00:00:37.964,599] <dbg> hio_lte_uart: process_rx_char: RSP: OK
ⓘ  [00:00:38.065,155] <dbg> hio_lte_uart: hio_lte_uart_send: CMD: AT%XSYSTEMMODE=0,1,0,0
ⓘ  [00:00:38.091,491] <dbg> hio_lte_uart: process_rx_char: RSP: OK
ⓘ  [00:00:38.192,047] <dbg> hio_lte_uart: hio_lte_uart_send: CMD: AT%REL14FEAT=1,1,1,1,0
ⓘ  [00:00:38.218,383] <dbg> hio_lte_uart: process_rx_char: RSP: OK
ⓘ  [00:00:38.318,939] <dbg> hio_lte_uart: hio_lte_uart_send: CMD: AT%XDATAPRFL=0
ⓘ  [00:00:38.343,261] <dbg> hio_lte_uart: process_rx_char: RSP: OK
ⓘ  [00:00:38.443,786] <dbg> hio_lte_uart: hio_lte_uart_send: CMD: AT%XSIM=1
ⓘ  [00:00:38.468,139] <dbg> hio_lte_uart: process_rx_char: RSP: OK
ⓘ  [00:00:38.568,695] <dbg> hio_lte_uart: hio_lte_uart_send: CMD: AT%XNETTIME=1
ⓘ  [00:00:38.593,017] <dbg> hio_lte_uart: process_rx_char: RSP: OK
ⓘ  [00:00:38.693,572] <dbg> hio_lte_uart: hio_lte_uart_send: CMD: AT%XTIME=1
ⓘ  [00:00:38.717,895] <dbg> hio_lte_uart: process_rx_char: RSP: OK
ⓘ  [00:00:38.818,420] <dbg> hio_lte_uart: hio_lte_uart_send: CMD: AT+CEREG=5
ⓘ  [00:00:38.842,773] <dbg> hio_lte_uart: process_rx_char: RSP: OK
ⓘ  [00:00:38.943,298] <dbg> hio_lte_uart: hio_lte_uart_send: CMD: AT+CGEREP=1
ⓘ  [00:00:38.967,681] <dbg> hio_lte_uart: process_rx_char: RSP: OK
ⓘ  [00:00:39.068,206] <dbg> hio_lte_uart: hio_lte_uart_send: CMD: AT+CSCON=1
ⓘ  [00:00:39.092,529] <dbg> hio_lte_uart: process_rx_char: RSP: OK
ⓘ  [00:00:39.193,084] <dbg> hio_lte_uart: hio_lte_uart_send: CMD: AT+CPSMS=1,,,"00111000","00000000"
ⓘ  [00:00:39.219,421] <dbg> hio_lte_uart: process_rx_char: RSP: OK
ⓘ  [00:00:39.319,946] <dbg> hio_lte_uart: hio_lte_uart_send: CMD: AT+CNEC=24
ⓘ  [00:00:39.344,299] <dbg> hio_lte_uart: process_rx_char: RSP: OK
ⓘ  [00:00:39.444,824] <dbg> hio_lte_uart: hio_lte_uart_send: CMD: AT+CMEE=1
ⓘ  [00:00:39.467,193] <dbg> hio_lte_uart: process_rx_char: RSP: OK
ⓘ  [00:00:39.567,718] <dbg> hio_lte_uart: hio_lte_uart_send: CMD: AT+CEPPI=1
ⓘ  [00:00:39.592,071] <dbg> hio_lte_uart: process_rx_char: RSP: OK
ⓘ  [00:00:39.692,596] <dbg> hio_lte_uart: hio_lte_uart_send: CMD: AT+CFUN=1
ⓘ  [00:00:39.739,074] <dbg> hio_lte_uart: process_rx_char: RSP: OK
ⓘ  [00:00:40.651,550] <dbg> hio_lte_uart: process_rx_char: URC: %XSIM: 1
ⓘ  [00:00:40.839,660] <dbg> hio_lte_uart: hio_lte_uart_send: CMD: AT+CIMI
ⓘ  [00:00:40.856,964] <dbg> hio_lte_uart: process_rx_char: RSP: 901288003957931
ⓘ  [00:00:40.857,482] <dbg> hio_lte_uart: process_rx_char: RSP: OK
ⓘ  [00:00:59.250,366] <dbg> hio_lte_uart: process_rx_char: URC: +CEREG: 2,"BBA0","0007361F",9
ⓘ  [00:00:59.250,885] <dbg> hio_lte_talk: hio_lte_talk_cereg: Parsed +CEREG

After AT%XFACTORYRESET=0 with 2s delay after #XSLEEP

 [00:00:53.503,875] <dbg> hio_lte_uart: hio_lte_uart_send: CMD: AT%HWVERSION
ⓘ  [00:00:53.530,120] <dbg> hio_lte_uart: process_rx_char: URC: %HWVERSION: nRF9160 SICA A00
ⓘ  [00:00:53.530,639] <dbg> hio_lte_uart: process_rx_char: RSP: OK
ⓘ  [00:00:53.631,195] <dbg> hio_lte_uart: hio_lte_uart_send: CMD: AT%SHORTSWVER
ⓘ  [00:00:53.657,012] <dbg> hio_lte_uart: process_rx_char: URC: %SHORTSWVER: nrf9160_1.3.0
ⓘ  [00:00:53.657,531] <dbg> hio_lte_uart: process_rx_char: RSP: OK
ⓘ  [00:00:53.758,087] <dbg> hio_lte_uart: hio_lte_uart_send: CMD: AT%XPOFWARN=1,30
ⓘ  [00:00:53.783,813] <dbg> hio_lte_uart: process_rx_char: RSP: OK
ⓘ  [00:00:53.884,368] <dbg> hio_lte_uart: hio_lte_uart_send: CMD: AT%XSYSTEMMODE=0,1,0,0
ⓘ  [00:00:53.910,705] <dbg> hio_lte_uart: process_rx_char: RSP: OK
ⓘ  [00:00:54.011,260] <dbg> hio_lte_uart: hio_lte_uart_send: CMD: AT%REL14FEAT=1,1,1,1,0
ⓘ  [00:00:54.035,614] <dbg> hio_lte_uart: process_rx_char: RSP: OK
ⓘ  [00:00:54.136,169] <dbg> hio_lte_uart: hio_lte_uart_send: CMD: AT%XDATAPRFL=0
ⓘ  [00:00:54.160,491] <dbg> hio_lte_uart: process_rx_char: RSP: OK
ⓘ  [00:00:54.261,016] <dbg> hio_lte_uart: hio_lte_uart_send: CMD: AT%XSIM=1
ⓘ  [00:00:54.285,369] <dbg> hio_lte_uart: process_rx_char: RSP: OK
ⓘ  [00:00:54.385,925] <dbg> hio_lte_uart: hio_lte_uart_send: CMD: AT%XNETTIME=1
ⓘ  [00:00:54.410,247] <dbg> hio_lte_uart: process_rx_char: RSP: OK
ⓘ  [00:00:54.510,772] <dbg> hio_lte_uart: hio_lte_uart_send: CMD: AT%XTIME=1
ⓘ  [00:00:54.535,125] <dbg> hio_lte_uart: process_rx_char: RSP: OK
ⓘ  [00:00:54.635,650] <dbg> hio_lte_uart: hio_lte_uart_send: CMD: AT+CEREG=5
ⓘ  [00:00:54.660,003] <dbg> hio_lte_uart: process_rx_char: RSP: OK
ⓘ  [00:00:54.760,528] <dbg> hio_lte_uart: hio_lte_uart_send: CMD: AT+CGEREP=1
ⓘ  [00:00:54.784,851] <dbg> hio_lte_uart: process_rx_char: RSP: OK
ⓘ  [00:00:54.885,375] <dbg> hio_lte_uart: hio_lte_uart_send: CMD: AT+CSCON=1
ⓘ  [00:00:54.909,759] <dbg> hio_lte_uart: process_rx_char: RSP: OK
ⓘ  [00:00:55.010,314] <dbg> hio_lte_uart: hio_lte_uart_send: CMD: AT+CPSMS=1,,,"00111000","00000000"
ⓘ  [00:00:55.036,651] <dbg> hio_lte_uart: process_rx_char: RSP: OK
ⓘ  [00:00:55.137,176] <dbg> hio_lte_uart: hio_lte_uart_send: CMD: AT+CNEC=24
ⓘ  [00:00:55.161,499] <dbg> hio_lte_uart: process_rx_char: RSP: OK
ⓘ  [00:00:55.262,023] <dbg> hio_lte_uart: hio_lte_uart_send: CMD: AT+CMEE=1
ⓘ  [00:00:55.284,362] <dbg> hio_lte_uart: process_rx_char: RSP: OK
ⓘ  [00:00:55.384,887] <dbg> hio_lte_uart: hio_lte_uart_send: CMD: AT+CEPPI=1
ⓘ  [00:00:55.409,240] <dbg> hio_lte_uart: process_rx_char: RSP: OK
ⓘ  [00:00:55.509,765] <dbg> hio_lte_uart: hio_lte_uart_send: CMD: AT+CFUN=1
ⓘ  [00:00:55.556,304] <dbg> hio_lte_uart: process_rx_char: RSP: OK
ⓘ  [00:00:56.472,747] <dbg> hio_lte_uart: process_rx_char: URC: %XSIM: 1
ⓘ  [00:00:56.656,890] <dbg> hio_lte_uart: hio_lte_uart_send: CMD: AT+CIMI
ⓘ  [00:00:56.674,163] <dbg> hio_lte_uart: process_rx_char: RSP: 901288003957931
ⓘ  [00:00:56.674,682] <dbg> hio_lte_uart: process_rx_char: RSP: OK
ⓘ  [00:01:14.213,531] <dbg> hio_lte_uart: process_rx_char: URC: +CEREG: 2,"BBA0","0007361F",9
ⓘ  [00:01:14.214,050] <dbg> hio_lte_talk: hio_lte_talk_cereg: Parsed +CEREG
ⓘ  [00:01:14.257,537] <dbg> test_lte: test_lte: Alive
ⓘ  [00:01:14.910,400] <dbg> hio_lte_uart: process_rx_char: URC: +CSCON: 1
ⓘ  [00:01:15.926,574] <dbg> hio_lte_uart: process_rx_char: URC: +CGEV: ME PDN ACT 0,0
ⓘ  [00:01:15.927,124] <dbg> hio_lte_uart: process_rx_char: URC: +CNEC_ESM: 50,0
ⓘ  [00:01:15.927,795] <dbg> hio_lte_uart: process_rx_char: URC: +CEREG: 5,"BBA0","0007361F",9,,,"00000000","00111000"
ⓘ  [00:01:15.928,375] <dbg> hio_lte_talk: hio_lte_talk_cereg: Parsed +CEREG
ⓘ  [00:01:16.028,991] <dbg> hio_lte_uart: hio_lte_uart_send: CMD: AT#XSOCKET=1,2,0
ⓘ  [00:01:16.047,424] <dbg> hio_lte_uart: process_rx_char: RSP: #XSOCKET: 1,2,0,17
ⓘ  [00:01:16.047,943] <dbg> hio_lte_uart: process_rx_char: RSP: OK
ⓘ  [00:01:16.048,400] <inf> hio_net_lte: Sleep initiated [0x20000680]
ⓘ  [00:01:16.146,148] <dbg> hio_lte_uart: process_rx_char: URC: %XTIME: ,"12706211154480","01"
ⓘ  [00:01:16.148,895] <dbg> hio_lte_uart: hio_lte_uart_send: CMD: AT#XSLEEP=2
ⓘ  [00:01:18.150,543] <inf> hio_net_lte: Attach succeeded [0x20000680]
ⓘ  [00:01:18.150,970] <inf> test_lte: Event `HIO_NET_LTE_EVENT_ATTACH_DONE`
ⓘ  [00:01:18.151,397] <inf> hio_net_lte: Dequeued message to send (port 7777, len 4) [0x20000680]
ⓘ  [00:01:18.151,947] <inf> hio_net_lte: Send started [0x20000680]
ⓘ  [00:01:18.152,557] <inf> hio_net_lte: Wake up initiated [0x20000680]
ⓘ  [00:01:19.263,275] <dbg> hio_lte_uart: hio_lte_uart_send: CMD: AT#XSENDTO="192.168.168.1",7777,0,"01020304"
ⓘ  [00:01:19.284,118] <dbg> hio_lte_uart: process_rx_char: RSP: #XSENDTO: 4
ⓘ  [00:01:19.284,606] <dbg> hio_lte_uart: process_rx_char: RSP: OK
ⓘ  [00:01:19.285,095] <inf> hio_net_lte: Sleep initiated [0x20000680]
ⓘ  [00:01:19.385,589] <dbg> hio_lte_uart: hio_lte_uart_send: CMD: AT#XSLEEP=2
ⓘ  [00:01:21.387,237] <inf> hio_net_lte: Send succeeded [0x20000680]
ⓘ  [00:01:21.387,664] <inf> test_lte: Event `HIO_NET_LTE_EVENT_SEND_DONE`
ⓘ  [00:01:21.388,061] <inf> hio_net_lte: Dequeued message to send (port 7777, len 4) [0x20000680]
ⓘ  [00:01:21.388,610] <inf> hio_net_lte: Send started [0x20000680]
ⓘ  [00:01:21.389,251] <inf> hio_net_lte: Wake up initiated [0x20000680]
ⓘ  [00:01:22.500,000] <dbg> hio_lte_uart: hio_lte_uart_send: CMD: AT#XSENDTO="192.168.168.1",7777,0,"01020304"
ⓘ  [00:01:22.520,874] <dbg> hio_lte_uart: process_rx_char: RSP: #XSENDTO: 4
ⓘ  [00:01:22.521,362] <dbg> hio_lte_uart: process_rx_char: RSP: OK
ⓘ  [00:01:22.521,850] <inf> hio_net_lte: Sleep initiated [0x20000680]
ⓘ  [00:01:22.622,344] <dbg> hio_lte_uart: hio_lte_uart_send: CMD: AT#XSLEEP=2
ⓘ  [00:01:24.623,992] <inf> hio_net_lte: Send succeeded [0x20000680]
ⓘ  [00:01:24.624,420] <inf> test_lte: Event `HIO_NET_LTE_EVENT_SEND_DONE`

0 Didrik Rokhaug over 4 years ago in reply to Michal Mühlpachr

I am still not entirely sure I understand your problem. Did the application stop working at the end of the "Then after RESET" log?

Or is it these lines, from the "Without 2s delay after #XSLEEP" log?

ⓘ  [00:01:22.749,206] <dbg> hio_lte_uart: hio_lte_uart_send: CMD: AT#XSLEEP=2
ⓘ  [00:01:22.750,793] <inf> hio_net_lte: Attach succeeded [0x20000680]
ⓘ  [00:01:22.751,220] <inf> test_lte: Event `HIO_NET_LTE_EVENT_ATTACH_DONE`
ⓘ  [00:01:22.751,678] <inf> hio_net_lte: Dequeued message to send (port 7777, len 4) [0x20000680]
ⓘ  [00:01:22.752,227] <inf> hio_net_lte: Send started [0x20000680]
ⓘ  [00:01:22.752,807] <inf> hio_net_lte: Wake up initiated [0x20000680]
ⓘ  [00:01:23.453,582] <dbg> test_lte: test_lte: Alive
ⓘ  [00:01:23.863,525] <dbg> hio_lte_uart: hio_lte_uart_send: CMD: AT#XSENDTO="192.168.168.1",7777,0,"01020304"
ⓘ  [00:01:28.868,225] <err> hio_lte_uart: Call `hio_sys_msgq_get` failed
ⓘ  [00:01:28.868,621] <err> hio_lte_talk: Call `hio_lte_uart_recv` failed
ⓘ  [00:01:28.869,018] <err> hio_net_lte: Call `hio_lte_talk_rsp` failed [0x20000680]
ⓘ  [00:01:28.869,537] <wrn> hio_net_lte: Send failed [0x20000680]
ⓘ  [00:01:28.869,934] <inf> test_lte: Event `HIO_NET_LTE_EVENT_SEND_ERROR`
ⓘ  [00:01:28.870,361] <inf> hio_net_lte: Attach started [0x20000680]
ⓘ  [00:01:29.880,950] <inf> hio_net_lte: Wake up initiated [0x20000680]
ⓘ  [00:01:30.991,607] <dbg> hio_lte_uart: hio_lte_uart_send: CMD: AT%HWVERSION
ⓘ  [00:01:35.993,377] <err> hio_lte_uart: Call `hio_sys_msgq_get` failed
ⓘ  [00:01:35.993,774] <err> hio_lte_talk: Call `hio_lte_uart_recv` failed
ⓘ  [00:01:35.994,201] <err> hio_net_lte: Call `hio_lte_talk_cmd_ok` failed [0x20000680]
ⓘ  [00:01:35.994,659] <wrn> hio_net_lte: Attach failed [0x20000680]
ⓘ  [00:01:35.995,086] <inf> test_lte: Event `HIO_NET_LTE_EVENT_ATTACH_ERROR`
ⓘ  [00:02:07.005,706] <inf> hio_net_lte: Wake up initiated [0x20000680]

Or a combination of the two?

0 Michal Mühlpachr over 4 years ago in reply to Didrik Rokhaug

Yes, application stop working at the end of the "Then after RESET" because attach URC and other URCs (+CEREG: 5,"... +CGEV: ME PDN ACT 0,0 +CSCON: 1) are not provided by the modem (modem successfully attached and got PDN service).

Sequence demonstrates, that RESET do not recover modem behavior.

Yes, application stop working after wakeup (after #XSLEEP) in "Without 2s delay after #XSLEEP also - modem responses are unexpected:

#XSOCKET: -114,"closed" - there was not any reason why socket would be closed after wakeup (reliably repeatable behavior). Socket is opened before #XSLEEP successfully.

Application then tries to recover by applying RESET to nRF9160, but it does not help because some URCs are not provided then.
Cancel
Vote Up 0 Vote Down

Sign in to reply

Verify Answer

Cancel

Reply

0 Michal Mühlpachr over 4 years ago in reply to Didrik Rokhaug

Yes, application stop working at the end of the "Then after RESET" because attach URC and other URCs (+CEREG: 5,"... +CGEV: ME PDN ACT 0,0 +CSCON: 1) are not provided by the modem (modem successfully attached and got PDN service).

Sequence demonstrates, that RESET do not recover modem behavior.

Yes, application stop working after wakeup (after #XSLEEP) in "Without 2s delay after #XSLEEP also - modem responses are unexpected:

#XSOCKET: -114,"closed" - there was not any reason why socket would be closed after wakeup (reliably repeatable behavior). Socket is opened before #XSLEEP successfully.

Application then tries to recover by applying RESET to nRF9160, but it does not help because some URCs are not provided then.
Cancel
Vote Up 0 Vote Down

Sign in to reply

Verify Answer

Cancel

Children

0 Didrik Rokhaug over 4 years ago in reply to Michal Mühlpachr

Hi, and sorry for the late reply.

Are you sure the modem sends URCs after the device has been waked up from sleep?

AT#XSLEEP=2 only powers off the UART, not the LTE link.

You can check if the LTE link is still up with AT+CEREG?

Best regards,

Didrik
Cancel
Vote Up 0 Vote Down

Sign in to reply

Verify Answer

Cancel