9160 System crash after CONFIG_DEVICE_POWER_MANAGEMENT is enabled

We have developed a project for the customer. In order to obtain longer battery life, we need to save power consumption, so we turn on CONFIG_DEVICE_POWER_MANAGEMENT. In order not to affect other places that need Uart, we will wake up Uart regularly according to TAU time.  But after 14 hours (our local TAU time is 30 minutes), the system crashes. Why?

Here are the sleep and wake operations I performed on the UART. Is there anything wrong with that?  

void uart_sleep_out(void)
{
#ifdef CONFIG_DEVICE_POWER_MANAGEMENT
	if(k_timer_remaining_get(&uart_sleep_in_timer) > 0)
		k_timer_stop(&uart_sleep_in_timer);
	k_timer_start(&uart_sleep_in_timer, K_SECONDS(UART_WAKE_HOLD_TIME_SEC), NULL);

	if(uart_is_waked)
		return;
	
	device_set_power_state(uart_ble, DEVICE_PM_ACTIVE_STATE, NULL, NULL);
	uart_irq_rx_enable(uart_ble);
	uart_irq_tx_enable(uart_ble);
	
	k_sleep(K_MSEC(10));
	
	uart_is_waked = true;

#ifdef UART_DEBUG
	LOGD("uart set active success!");
#endif
#endif
}

void uart_sleep_in(void)
{
#ifdef CONFIG_DEVICE_POWER_MANAGEMENT
	if(!uart_is_waked)
		return;
	
	uart_irq_rx_disable(uart_ble);
	uart_irq_tx_disable(uart_ble);
	device_set_power_state(uart_ble, DEVICE_PM_LOW_POWER_STATE, NULL, NULL);

	k_sleep(K_MSEC(10));
	
	uart_is_waked = false;

#ifdef UART_DEBUG
	LOGD("uart set low power success!");
#endif
#endif
}

  • Hi,

     

    We have developed a project for the customer. In order to obtain longer battery life, we need to save power consumption, so we turn on CONFIG_DEVICE_POWER_MANAGEMENT. In order not to affect other places that need Uart, we will wake up Uart regularly according to TAU time.  But after 14 hours (our local TAU time is 30 minutes), the system crashes. Why?

    Do you have a fault log or similar showing the issue?

     

    Kind regards,

    Håkon

  • can't see any useful information in logs, and based on my experience with previous tests, it looks like some application is using the uart while it's dormant.

  • Can you please share the log?

     

    Kind regards,

    Håkon

  • OK,I'll share this Log with you along with a screenshot of the power curve that PPK captured.

    # SEGGER J-Link RTT Viewer V6.54c Terminal Log File
    # Compiled: 17:04:11 on Nov  7 2019
    # Logging started @ 05 Nov 2021 18:58:26
    
    00> [00447922] <inf> auri: [18:39:57:669]..nb_link>>Connected to LTE network
    00> 
    00> [00447954] <inf> auri: [18:39:57:670]..GetModemDateTime>>Get CCLK fail!
    00> 
    00> [00448003] <inf> auri: [18:39:57:671]..GetModemInfor>>imei:351358811150470
    00> 
    00> 
    00> [00448035] <inf> auri: [18:39:57:672]..GetModemInfor>>imsi:460049299701741
    00> 
    00> 
    00> [00448065] <inf> auri: [18:39:57:673]..GetModemInfor>>iccid:89860472042071181741
    00> 
    00> 
    00> [00448093] <inf> auri: [18:39:57:674]..GetModemInfor>>MODEM version:mfw_nrf9160_1.2.0
    00> 
    00> 
    00> [00448130] <inf> auri: [18:39:57:675]..GetModemInfor>>MODEM parameter:%XMONITOR: 1,"","","46000","1D29",9,8,"0D1E2D42",11,3684,68,36,"","00000100","11100000","00111110"
    00> 
    00> 
    00> [00448154] <inf> auri: [18:39:57:676]..DecodeModemMonitor>>%XMONITOR: 1,"","","46000","1D29",9,8,"0D1E2D42",11,3684,68,36,"","00000100","11100000","00111110"
    00> 
    00> 
    00> [00448176] <inf> auri: [18:39:57:677]..DecodeModemMonitor>>rsrp:68
    00> 
    00> [00448190] <inf> auri: [18:39:57:677]..modem_rsrp_handler>>rsrp_value:68
    00> 
    00> [00448205] <inf> auri: [18:39:57:678]..DecodeModemMonitor>>Active-Time:"00000100"
    00> 
    00> [00448221] <inf> auri: [18:39:57:678]..DecodeModemMonitor>>g_act_time:8
    00> 
    00> [00448236] <inf> auri: [18:39:57:678]..DecodeModemMonitor>>Periodic-TAU-ext:"11100000"
    00> 
    00> [00448253] <inf> auri: [18:39:57:679]..DecodeModemMonitor>>Periodic-TAU:"00111110"
    00> 
    00> [00448268] <inf> auri: [18:39:57:679]..DecodeModemMonitor>>g_tau_time:1800
    00> 
    00> [00465002] <inf> auri: [18:39:58:190]..NBRedrawSignal>>+CSCON: 0,1
    00> 
    00> 
    00> [00465017] <inf> auri: [18:39:58:191]..NBRedrawSignal>>mode:1
    00> 
    00> [00480756] <inf> auri: [18:39:58:671]..GetModemDateTime>>%CCLK: "21/11/05,10:58:32+32",0
    00> 
    00> 
    00> [00480779] <inf> auri: [18:58:32:671]..GetModemDateTime>>real time:2021/11/05,18:58:32,05
    00> 
    00> [00513821] <inf> auri: [18:58:33:680]..mqtt_link>>begin
    00> 
    00> [00513845] <inf> auri: [18:58:33:681]..broker_init>>IPv4 Address found 47.107.51.89
    00> 
    00> [00569179] <inf> auri: [18:58:35:369]..mqtt_evt_handler>>MQTT client connected!
    00> 
    00> [00569196] <inf> auri: [18:58:35:370]..subscribe>>Subscribing to:device/auri/351358811150470, len:27
    00> 
    00> [00569222] <inf> auri: [18:58:35:371]..NBSendPowerOnInfor>>pwr on infor:{1:1:0:0:351358811150470:T12:460049299701741,89860472042071181741,68,+8,91,1636138715}
    00> 
    00> [00569245] <inf> auri: [18:58:35:371]..MqttSendData>>data add ret:1
    00> 
    00> [00569258] <inf> auri: [18:58:35:372]..MqttSendData>>begin 001
    00> 
    00> [00569273] <inf> auri: [18:58:35:372]..MqttDicConnectStart>>begin
    00> 
    00> [00597329] <inf> auri: [18:58:36:228]..mqtt_evt_handler>>PUBACK packet id: 48256
    00> 
    00> [02535367] <inf> auri: [18:59:35:373]..MqttDisConnectCallBack>>begin
    00> 
    00> [02535381] <inf> auri: [18:59:35:373]..MqttDisConnect>>begin
    00> 
    00> [02535458] <inf> auri: [18:59:35:375]..modem_rsrp_handler>>rsrp_value:255
    00> 
    00> [02535623] <inf> auri: [18:59:35:380]..mqtt_evt_handler>>MQTT client disconnected 0
    00> 
    00> [02541578] <inf> auri: [18:59:35:562]..mqtt_link>>POLLNVAL
    00> 
    00> [02541591] <inf> auri: [18:59:35:563]..mqtt_link>>[mqtt_link]: Disconnecting MQTT client...
    00> 
    00> [02541609] <inf> auri: [18:59:35:563]..mqtt_link>>[mqtt_link]: Could not disconnect MQTT client. Error: -128
    00> 
    00> [02541630] <inf> auri: [18:59:35:564]..NBRedrawSignal>>+CSCON: 0,0
    00> 
    00> 
    00> [02541645] <inf> auri: [18:59:35:564]..NBRedrawSignal>>mode:0
    00> 
    00> [02541658] <inf> auri: [18:59:35:565]..NBRedrawSignal>>reg stat:1
    00> 
    00> [02956154] <inf> auri: [18:59:48:214]..modem_rsrp_handler>>rsrp_value:65
    00> 
    00> [02967568] <inf> auri: [18:59:48:562]..NBRedrawSignal>>+CSCON: 0,1
    00> 
    00> 
    00> [02967583] <inf> auri: [18:59:48:563]..NBRedrawSignal>>mode:1
    00> 
    00> [04922240] <inf> auri: [19:00:48:214]..modem_rsrp_handler>>rsrp_value:255
    00> 
    00> [04933845] <inf> auri: [19:00:48:568]..NBRedrawSignal>>+CSCON: 0,0
    00> 
    00> 
    00> [04933859] <inf> auri: [19:00:48:569]..NBRedrawSignal>>mode:0
    00> 
    00> [04933873] <inf> auri: [19:00:48:569]..NBRedrawSignal>>reg stat:1
    00> 
    00> [06363382] <inf> auri: [19:01:32:194]..button_handler>>button_state:1, has_changed:1
    00> 
    00> [06363399] <inf> auri: [19:01:32:195]..key_event_handler>>key_code:1, key_type:1, KEY_SOS:0
    00> 
    00> [06370367] <inf> auri: [19:01:32:408]..button_handler>>button_state:0, has_changed:1
    00> 
    00> [06370383] <inf> auri: [19:01:32:408]..key_event_handler>>key_code:1, key_type:0, KEY_SOS:0
    00> 
    00> [10344234] <inf> auri: [19:03:33:680]..UartSleepInCallBack>>begin
    00> 
    00> [10344250] <inf> auri: [19:03:33:681]..UartMsgProc>>uart_sleep!
    00> 
    00> [10344264] <inf> auri: [19:03:33:681]..gps_is_working>>gps_is_on:0
    00> 
    00> [10344278] <inf> auri: [19:03:33:682]..MqttIsConnected>>mqtt_connected:0
    00> 
    00> [10344292] <inf> auri: [19:03:33:682]..nb_is_connecting>>nb_connecting_flag:0
    00> 
    00> [10344307] <inf> auri: [19:03:33:683]..wifi_is_working>>wifi_is_on:0
    00> 
    00> [10344667] <inf> auri: [19:03:33:694]..uart_sleep_in>>uart set low power success!
    00> 
    00> [18395058] <inf> auri: [19:07:39:372]..button_handler>>button_state:1, has_changed:1
    00> 
    00> [18395075] <inf> auri: [19:07:39:372]..key_event_handler>>key_code:1, key_type:1, KEY_SOS:0
    00> 
    00> [18401050] <inf> auri: [19:07:39:555]..button_handler>>button_state:0, has_changed:1
    00> 
    00> [18401066] <inf> auri: [19:07:39:555]..key_event_handler>>key_code:1, key_type:0, KEY_SOS:0
    00> 
    00> [31582770] <inf> auri: [19:14:21:829]..button_handler>>button_state:1, has_changed:1
    00> 
    00> [31582787] <inf> auri: [19:14:21:830]..key_event_handler>>key_code:1, key_type:1, KEY_SOS:0
    00> 
    00> [31588099] <inf> auri: [19:14:21:992]..button_handler>>button_state:0, has_changed:1
    00> 
    00> [31588115] <inf> auri: [19:14:21:992]..key_event_handler>>key_code:1, key_type:0, KEY_SOS:0
    00> 
    00> [44605293] <inf> auri: [19:20:59:245]..button_handler>>button_state:1, has_changed:1
    00> 
    00> [44605310] <inf> auri: [19:20:59:245]..key_event_handler>>key_code:1, key_type:1, KEY_SOS:0
    00> 
    00> [44610291] <inf> auri: [19:20:59:397]..button_handler>>button_state:0, has_changed:1
    00> 
    00> [44610307] <inf> auri: [19:20:59:398]..key_event_handler>>key_code:1, key_type:0, KEY_SOS:0
    00> 
    00> [49612391] <inf> auri: [19:23:31:049]..button_handler>>button_state:1, has_changed:1
    00> 
    00> [49612408] <inf> auri: [19:23:31:050]..key_event_handler>>key_code:1, key_type:1, KEY_SOS:0
    00> 
    00> [49617721] <inf> auri: [19:23:32:212]..button_handler>>button_state:0, has_changed:1
    00> 
    00> [49617737] <inf> auri: [19:23:32:213]..key_event_handler>>key_code:1, key_type:0, KEY_SOS:0
    00> 
    00> [62933247] <inf> auri: [19:30:18:570]..TauWakeUpUartCallBack>>begin
    00> 
    00> [62933264] <inf> auri: [19:30:18:570]..UartMsgProc>>uart_wake!
    00> 
    00> [62933336] <inf> auri: [19:30:18:572]..modem_rsrp_handler>>rsrp_value:64
    00> 
    00> [62933680] <inf> auri: [19:30:18:583]..uart_sleep_out>>uart set active success!
    00> 
    00> [62953790] <inf> auri: [19:30:19:197]..modem_rsrp_handler>>rsrp_value:64
    00> 
    00> [62953870] <inf> auri: [19:30:19:199]..modem_rsrp_handler>>rsrp_value:255
    00> 
    00> [62953889] <inf> auri: [19:30:19:200]..NBRedrawSignal>>+CSCON: 0,0
    00> 
    00> 
    00> [62953904] <inf> auri: [19:30:19:200]..NBRedrawSignal>>mode:0
    00> 
    00> [62953917] <inf> auri: [19:30:19:200]..NBRedrawSignal>>reg stat:1
    00> 
    00> [72763676] <inf> auri: [19:35:18:571]..UartSleepInCallBack>>begin
    00> 
    00> [72763693] <inf> auri: [19:35:18:571]..UartMsgProc>>uart_sleep!
    00> 
    00> [72763707] <inf> auri: [19:35:18:571]..gps_is_working>>gps_is_on:0
    00> 
    00> [72763721] <inf> auri: [19:35:18:572]..MqttIsConnected>>mqtt_connected:0
    00> 
    00> [72763735] <inf> auri: [19:35:18:572]..nb_is_connecting>>nb_connecting_flag:0
    00> 
    00> [72763750] <inf> auri: [19:35:18:573]..wifi_is_working>>wifi_is_on:0
    00> 
    00> [72764110] <inf> auri: [19:35:18:584]..uart_sleep_in>>uart set low power success!
    00> 
    00> [120953290] <inf> auri: [19:59:49:201]..TauWakeUpUartCallBack>>begin
    00> 
    00> [120953308] <inf> auri: [19:59:49:201]..UartMsgProc>>uart_wake!
    00> 
    00> [120953653] <inf> auri: [19:59:49:212]..uart_sleep_out>>uart set active success!
    00> 
    00> [122887034] <inf> auri: [20:00:48:214]..modem_rsrp_handler>>rsrp_value:67
    00> 
    00> [122887116] <inf> auri: [20:00:48:217]..modem_rsrp_handler>>rsrp_value:255
    00> 
    00> [122898645] <inf> auri: [20:00:48:568]..NBRedrawSignal>>+CSCON: 0,0
    00> 
    00> 
    00> [122898660] <inf> auri: [20:00:48:569]..NBRedrawSignal>>mode:0
    00> 
    00> [122898673] <inf> auri: [20:00:48:569]..NBRedrawSignal>>reg stat:1
    00> 
    00> [130783722] <inf> auri: [20:04:49:202]..UartSleepInCallBack>>begin
    00> 
    00> [130783738] <inf> auri: [20:04:49:202]..UartMsgProc>>uart_sleep!
    00> 
    00> [130783752] <inf> auri: [20:04:49:203]..gps_is_working>>gps_is_on:0
    00> 
    00> [130783766] <inf> auri: [20:04:49:203]..MqttIsConnected>>mqtt_connected:0
    00> 
    00> [130783780] <inf> auri: [20:04:49:204]..nb_is_connecting>>nb_connecting_flag:0
    00> 
    00> [130783795] <inf> auri: [20:04:49:204]..wifi_is_working>>wifi_is_on:0
    00> 
    00> [130784155] <inf> auri: [20:04:49:215]..uart_sleep_in>>uart set low power success!
    00> 
    00> [180898047] <inf> auri: [20:30:18:570]..TauWakeUpUartCallBack>>begin
    00> 
    00> [180898064] <inf> auri: [20:30:18:570]..UartMsgProc>>uart_wake!
    00> 
    00> [180898136] <inf> auri: [20:30:18:572]..modem_rsrp_handler>>rsrp_value:66
    00> 
    00> [180898216] <inf> auri: [20:30:18:575]..modem_rsrp_handler>>rsrp_value:255
    00> 
    00> [180898408] <inf> auri: [20:30:18:581]..uart_sleep_out>>uart set active success!
    00> 
    00> [180918579] <inf> auri: [20:30:19:196]..NBRedrawSignal>>+CSCON: 0,0
    00> 
    00> 
    00> [180918594] <inf> auri: [20:30:19:197]..NBRedrawSignal>>mode:0
    00> 
    00> [180918607] <inf> auri: [20:30:19:197]..NBRedrawSignal>>reg stat:1
    00> 
    00> [190728477] <inf> auri: [20:35:18:571]..UartSleepInCallBack>>begin
    00> 
    00> [190728494] <inf> auri: [20:35:18:571]..UartMsgProc>>uart_sleep!
    00> 
    00> [190728508] <inf> auri: [20:35:18:572]..gps_is_working>>gps_is_on:0
    00> 
    00> [190728522] <inf> auri: [20:35:18:572]..MqttIsConnected>>mqtt_connected:0
    00> 
    00> [190728536] <inf> auri: [20:35:18:572]..nb_is_connecting>>nb_connecting_flag:0
    00> 
    00> [190728551] <inf> auri: [20:35:18:573]..wifi_is_working>>wifi_is_on:0
    00> 
    00> [190728911] <inf> auri: [20:35:18:584]..uart_sleep_in>>uart set low power success!
    00> 
    00> [238917981] <inf> auri: [20:59:49:198]..TauWakeUpUartCallBack>>begin
    00> 
    00> [238917998] <inf> auri: [20:59:49:198]..UartMsgProc>>uart_wake!
    00> 
    00> [238918342] <inf> auri: [20:59:49:209]..uart_sleep_out>>uart set active success!
    00> 
    00> [240851834] <inf> auri: [21:00:48:214]..modem_rsrp_handler>>rsrp_value:65
    00> 
    00> [240851915] <inf> auri: [21:00:48:217]..modem_rsrp_handler>>rsrp_value:255
    00> 
    00> [240863445] <inf> auri: [21:00:48:568]..NBRedrawSignal>>+CSCON: 0,0
    00> 
    00> 
    00> [240863460] <inf> auri: [21:00:48:569]..NBRedrawSignal>>mode:0
    00> 
    00> [240863473] <inf> auri: [21:00:48:569]..NBRedrawSignal>>reg stat:1
    00> 
    00> [248748412] <inf> auri: [21:04:49:198]..UartSleepInCallBack>>begin
    00> 
    00> [248748428] <inf> auri: [21:04:49:199]..UartMsgProc>>uart_sleep!
    00> 
    00> [248748442] <inf> auri: [21:04:49:199]..gps_is_working>>gps_is_on:0
    00> 
    00> [248748456] <inf> auri: [21:04:49:200]..MqttIsConnected>>mqtt_connected:0
    00> 
    00> [248748471] <inf> auri: [21:04:49:200]..nb_is_connecting>>nb_connecting_flag:0
    00> 
    00> [248748485] <inf> auri: [21:04:49:201]..wifi_is_working>>wifi_is_on:0
    00> 
    00> [248748845] <inf> auri: [21:04:49:212]..uart_sleep_in>>uart set low power success!
    00> 
    00> [298862846] <inf> auri: [21:30:18:570]..TauWakeUpUartCallBack>>begin
    00> 
    00> [298862864] <inf> auri: [21:30:18:570]..UartMsgProc>>uart_wake!
    00> 
    00> [298862936] <inf> auri: [21:30:18:572]..modem_rsrp_handler>>rsrp_value:66
    00> 
    00> [298863015] <inf> auri: [21:30:18:575]..modem_rsrp_handler>>rsrp_value:255
    00> 
    00> [298863208] <inf> auri: [21:30:18:581]..uart_sleep_out>>uart set active success!
    00> 
    00> [298883379] <inf> auri: [21:30:19:196]..NBRedrawSignal>>+CSCON: 0,0
    00> 
    00> 
    00> [298883394] <inf> auri: [21:30:19:197]..NBRedrawSignal>>mode:0
    00> 
    00> [298883407] <inf> auri: [21:30:19:197]..NBRedrawSignal>>reg stat:1
    00> 
    00> [308693277] <inf> auri: [21:35:18:571]..UartSleepInCallBack>>begin
    00> 
    00> [308693294] <inf> auri: [21:35:18:571]..UartMsgProc>>uart_sleep!
    00> 
    00> [308693308] <inf> auri: [21:35:18:572]..gps_is_working>>gps_is_on:0
    00> 
    00> [308693322] <inf> auri: [21:35:18:572]..MqttIsConnected>>mqtt_connected:0
    00> 
    00> [308693336] <inf> auri: [21:35:18:572]..nb_is_connecting>>nb_connecting_flag:0
    00> 
    00> [308693351] <inf> auri: [21:35:18:573]..wifi_is_working>>wifi_is_on:0
    00> 
    00> [308693712] <inf> auri: [21:35:18:584]..uart_sleep_in>>uart set low power success!
    00> 
    00> [341732753] <inf> auri: [21:52:06:855]..PMUMsgProcess>>alert
    00> 
    00> [341732775] <inf> auri: [21:52:06:856]..pmu_alert_proc>>status:00
    00> 
    00> [356882781] <inf> auri: [21:59:49:198]..TauWakeUpUartCallBack>>begin
    00> 
    00> [356882798] <inf> auri: [21:59:49:198]..UartMsgProc>>uart_wake!
    00> 
    00> [356883142] <inf> auri: [21:59:49:209]..uart_sleep_out>>uart set active success!
    00> 
    00> [358816634] <inf> auri: [22:00:48:214]..modem_rsrp_handler>>rsrp_value:65
    00> 
    00> [358816715] <inf> auri: [22:00:48:217]..modem_rsrp_handler>>rsrp_value:255
    00> 
    00> [358828245] <inf> auri: [22:00:48:568]..NBRedrawSignal>>+CSCON: 0,0
    00> 
    00> 
    00> [358828260] <inf> auri: [22:00:48:569]..NBRedrawSignal>>mode:0
    00> 
    00> [358828273] <inf> auri: [22:00:48:569]..NBRedrawSignal>>reg stat:1
    00> 
    00> [366713212] <inf> auri: [22:04:49:198]..UartSleepInCallBack>>begin
    00> 
    00> [366713228] <inf> auri: [22:04:49:199]..UartMsgProc>>uart_sleep!
    00> 
    00> [366713242] <inf> auri: [22:04:49:199]..gps_is_working>>gps_is_on:0
    00> 
    00> [366713256] <inf> auri: [22:04:49:200]..MqttIsConnected>>mqtt_connected:0
    00> 
    00> [366713271] <inf> auri: [22:04:49:200]..nb_is_connecting>>nb_connecting_flag:0
    00> 
    00> [366713286] <inf> auri: [22:04:49:201]..wifi_is_working>>wifi_is_on:0
    00> 
    00> [366713646] <inf> auri: [22:04:49:212]..uart_sleep_in>>uart set low power success!
    00> 
    00> [387166402] <inf> auri: [22:15:13:380]..PMUMsgProcess>>alert
    00> 
    00> [387166424] <inf> auri: [22:15:13:381]..pmu_alert_proc>>status:00
    00> 
    00> [416827647] <inf> auri: [22:30:18:570]..TauWakeUpUartCallBack>>begin
    00> 
    00> [416827664] <inf> auri: [22:30:18:570]..UartMsgProc>>uart_wake!
    00> 
    00> [416827736] <inf> auri: [22:30:18:572]..modem_rsrp_handler>>rsrp_value:66
    00> 
    00> [416828008] <inf> auri: [22:30:18:581]..uart_sleep_out>>uart set active success!
    00> 
    00> [416848179] <inf> auri: [22:30:19:196]..NBRedrawSignal>>+CSCON: 0,0
    00> 
    00> 
    00> [416848194] <inf> auri: [22:30:19:197]..NBRedrawSignal>>mode:0
    00> 
    00> [416848207] <inf> auri: [22:30:19:197]..NBRedrawSignal>>reg stat:1
    00> 
    00> [417799040] <inf> auri: [22:30:48:214]..modem_rsrp_handler>>rsrp_value:255
    00> 
    00> [417810645] <inf> auri: [22:30:48:568]..NBRedrawSignal>>+CSCON: 0,0
    00> 
    00> 
    00> [417810660] <inf> auri: [22:30:48:569]..NBRedrawSignal>>mode:0
    00> 
    00> [417810673] <inf> auri: [22:30:48:569]..NBRedrawSignal>>reg stat:1
    00> 
    00> [426658077] <inf> auri: [22:35:18:571]..UartSleepInCallBack>>begin
    00> 
    00> [426658094] <inf> auri: [22:35:18:571]..UartMsgProc>>uart_sleep!
    00> 
    00> [426658108] <inf> auri: [22:35:18:572]..gps_is_working>>gps_is_on:0
    00> 
    00> [426658122] <inf> auri: [22:35:18:572]..MqttIsConnected>>mqtt_connected:0
    00> 
    00> [426658136] <inf> auri: [22:35:18:572]..nb_is_connecting>>nb_connecting_flag:0
    00> 
    00> [426658151] <inf> auri: [22:35:18:573]..wifi_is_working>>wifi_is_on:0
    00> 
    00> [426658511] <inf> auri: [22:35:18:584]..uart_sleep_in>>uart set low power success!
    00> 
    00> [475810048] <inf> auri: [23:00:18:570]..TauWakeUpUartCallBack>>begin
    00> 
    00> [475810065] <inf> auri: [23:00:18:570]..UartMsgProc>>uart_wake!
    00> 
    00> [475810137] <inf> auri: [23:00:18:572]..modem_rsrp_handler>>rsrp_value:66
    00> 
    00> [475810410] <inf> auri: [23:00:18:581]..uart_sleep_out>>uart set active success!
    00> 
    00> [475830579] <inf> auri: [23:00:19:196]..NBRedrawSignal>>+CSCON: 0,0
    00> 
    00> 
    00> [475830594] <inf> auri: [23:00:19:197]..NBRedrawSignal>>mode:0
    00> 
    00> [475830607] <inf> auri: [23:00:19:197]..NBRedrawSignal>>reg stat:1
    00> 
    00> [476781440] <inf> auri: [23:00:48:214]..modem_rsrp_handler>>rsrp_value:255
    00> 
    00> [476793045] <inf> auri: [23:00:48:568]..NBRedrawSignal>>+CSCON: 0,0
    00> 
    00> 
    00> [476793060] <inf> auri: [23:00:48:569]..NBRedrawSignal>>mode:0
    00> 
    00> [476793073] <inf> auri: [23:00:48:569]..NBRedrawSignal>>reg stat:1
    00> 
    00> [485640479] <inf> auri: [23:05:18:571]..UartSleepInCallBack>>begin
    00> 
    00> [485640496] <inf> auri: [23:05:18:571]..UartMsgProc>>uart_sleep!
    00> 
    00> [485640509] <inf> auri: [23:05:18:572]..gps_is_working>>gps_is_on:0
    00> 
    00> [485640523] <inf> auri: [23:05:18:572]..MqttIsConnected>>mqtt_connected:0
    00> 
    00> [485640538] <inf> auri: [23:05:18:572]..nb_is_connecting>>nb_connecting_flag:0
    00> 
    00> [485640552] <inf> auri: [23:05:18:573]..wifi_is_working>>wifi_is_on:0
    00> 
    00> [485640912] <inf> auri: [23:05:18:584]..uart_sleep_in>>uart set low power success!
    00> 
    00> [489237732] <inf> auri: [23:07:08:350]..PMUMsgProcess>>alert
    00> 
    00> [489237754] <inf> auri: [23:07:08:351]..pmu_alert_proc>>status:00
    00> 
    00> [517020985] <inf> auri: [23:21:16:228]..PMUMsgProcess>>alert
    00> 
    00> [517021007] <inf> auri: [23:21:16:228]..pmu_alert_proc>>status:00
    00> 
    00> [534792448] <inf> auri: [23:30:18:570]..TauWakeUpUartCallBack>>begin
    00> 
    00> [534792465] <inf> auri: [23:30:18:570]..UartMsgProc>>uart_wake!
    00> 
    00> [534792537] <inf> auri: [23:30:18:572]..modem_rsrp_handler>>rsrp_value:65
    00> 
    00> [534792809] <inf> auri: [23:30:18:581]..uart_sleep_out>>uart set active success!
    00> 
    00> [534812780] <inf> auri: [23:30:19:190]..NBRedrawSignal>>+CSCON: 0,0
    00> 
    00> 
    00> [534812794] <inf> auri: [23:30:19:191]..NBRedrawSignal>>mode:0
    00> 
    00> [534812808] <inf> auri: [23:30:19:191]..NBRedrawSignal>>reg stat:1
    00> 
    00> [535763834] <inf> auri: [23:30:48:214]..modem_rsrp_handler>>rsrp_value:65
    00> 
    00> [535763910] <inf> auri: [23:30:48:216]..modem_rsrp_handler>>rsrp_value:65
    00> 
    00> [535763991] <inf> auri: [23:30:48:219]..modem_rsrp_handler>>rsrp_value:255
    00> 
    00> [535775445] <inf> auri: [23:30:48:568]..NBRedrawSignal>>+CSCON: 0,0
    00> 
    00> 
    00> [535775460] <inf> auri: [23:30:48:569]..NBRedrawSignal>>mode:0
    00> 
    00> [535775473] <inf> auri: [23:30:48:569]..NBRedrawSignal>>reg stat:1
    00> 
    00> [543738466] <inf> auri: [23:34:51:581]..PMUMsgProcess>>alert
    00> 
    00> [543738488] <inf> auri: [23:34:51:581]..pmu_alert_proc>>status:00
    00> 
    00> [544622879] <inf> auri: [23:35:18:571]..UartSleepInCallBack>>begin
    00> 
    00> [544622800> [544622895] <inf> auri: [23:35:18:571]..UartMsgProc>>uart_sleep!
    00> 
    00> [544622909] <inf> auri: [23:35:18:572]..gps_is_working>>gps_is_on:0
    00> 
    00> [544622923] <inf> auri: [23:35:18:572]..MqttIsConnected>>mqtt_connected:0
    00> 
    00> [544622937] <inf> auri: [23:35:18:572]..nb_is_connecting>>nb_connecting_flag:0
    00> 
    00> [544622952] <inf> auri: [23:35:18:573]..wifi_is_working>>wifi_is_on:0
    00> 
    00> [544623312] <inf> auri: [23:35:18:584]..uart_sleep_in>>uart set low power success!
    00> 
    00> [582162560] <inf> auri: [23:54:24:191]..PMUMsgProcess>>alert
    00> 
    00> [582162582] <inf> auri: [23:54:24:191]..pmu_alert_proc>>status:00
    00> 
    00> [593774847] <inf> auri: [00:00:18:570]..TauWakeUpUartCallBack>>begin
    00> 
    00> [593774864] <inf> auri: [00:00:18:570]..UartMsgProc>>uart_wake!
    00> 
    00> [593775210] <inf> auri: [00:00:18:581]..uart_sleep_out>>uart set active success!
    00> 
    00> [594746234] <inf> auri: [00:00:48:214]..modem_rsrp_handler>>rsrp_value:64
    00> 
    00> [594746646] <inf> auri: [00:00:48:227]..modem_rsrp_handler>>rsrp_value:255
    00> 
    00> [594757845] <inf> auri: [00:00:48:568]..NBRedrawSignal>>+CSCON: 0,0
    00> 
    00> 
    00> [594757860] <inf> auri: [00:00:48:569]..NBRedrawSignal>>mode:0
    00> 
    00> [594757873] <inf> auri: [00:00:48:569]..NBRedrawSignal>>reg stat:1
    00> 
    00> [603605278] <inf> auri: [00:05:18:571]..UartSleepInCallBack>>begin
    00> 
    00> [603605295] <inf> auri: [00:05:18:571]..UartMsgProc>>uart_sleep!
    00> 
    00> [603605308] <inf> auri: [00:05:18:572]..gps_is_working>>gps_is_on:0
    00> 
    00> [603605322] <inf> auri: [00:05:18:572]..MqttIsConnected>>mqtt_connected:0
    00> 
    00> [603605337] <inf> auri: [00:05:18:572]..nb_is_connecting>>nb_connecting_flag:0
    00> 
    00> [603605351] <inf> auri: [00:05:18:573]..wifi_is_working>>wifi_is_on:0
    00> 
    00> [603605712] <inf> auri: [00:05:18:584]..uart_sleep_in>>uart set low power success!
    00> 
    00> [652757247] <inf> auri: [00:30:18:570]..TauWakeUpUartCallBack>>begin
    00> 
    00> [652757264] <inf> auri: [00:30:18:570]..UartMsgProc>>uart_wake!
    00> 
    00> [652757609] <inf> auri: [00:30:18:581]..uart_sleep_out>>uart set active success!
    00> 
    00> [653728634] <inf> auri: [00:30:48:214]..modem_rsrp_handler>>rsrp_value:66
    00> 
    00> [653728715] <inf> auri: [00:30:48:217]..modem_rsrp_handler>>rsrp_value:255
    00> 
    00> [653740245] <inf> auri: [00:30:48:568]..NBRedrawSignal>>+CSCON: 0,0
    00> 
    00> 
    00> [653740260] <inf> auri: [00:30:48:569]..NBRedrawSignal>>mode:0
    00> 
    00> [653740273] <inf> auri: [00:30:48:569]..NBRedrawSignal>>reg stat:1
    00> 
    00> [662587678] <inf> auri: [00:35:18:571]..UartSleepInCallBack>>begin
    00> 
    00> [662587694] <inf> auri: [00:35:18:571]..UartMsgProc>>uart_sleep!
    00> 
    00> [662587708] <inf> auri: [00:35:18:572]..gps_is_working>>gps_is_on:0
    00> 
    00> [662587722] <inf> auri: [00:35:18:572]..MqttIsConnected>>mqtt_connected:0
    00> 
    00> [662587737] <inf> auri: [00:35:18:572]..nb_is_connecting>>nb_connecting_flag:0
    00> 
    00> [662587751] <inf> auri: [00:35:18:573]..wifi_is_working>>wifi_is_on:0
    00> 
    00> [662588111] <inf> auri: [00:35:18:584]..uart_sleep_in>>uart set low power success!
    00> 
    00> [666321616] <inf> auri: [00:37:12:521]..PMUMsgProcess>>alert
    00> 
    00> [666321638] <inf> auri: [00:37:12:522]..pmu_alert_proc>>status:00
    00> 
    00> [701791299] <inf> auri: [00:55:14:970]..PMUMsgProcess>>alert
    00> 
    00> [701791321] <inf> auri: [00:55:14:971]..pmu_alert_proc>>status:00
    00> 
    00> [711739647] <inf> auri: [01:00:18:570]..TauWakeUpUartCallBack>>begin
    00> 
    00> [711739664] <inf> auri: [01:00:18:570]..UartMsgProc>>uart_wake!
    00> 
    00> [711740010] <inf> auri: [01:00:18:581]..uart_sleep_out>>uart set active success!
    00> 
    00> [712711034] <inf> auri: [01:00:48:214]..modem_rsrp_handler>>rsrp_value:64
    00> 
    00> [712711445] <inf> auri: [01:00:48:227]..modem_rsrp_handler>>rsrp_value:255
    00> 
    00> [712722645] <inf> auri: [01:00:48:568]..NBRedrawSignal>>+CSCON: 0,0
    00> 
    00> 
    00> [712722660] <inf> auri: [01:00:48:569]..NBRedrawSignal>>mode:0
    00> 
    00> [712722673] <inf> auri: [01:00:48:569]..NBRedrawSignal>>reg stat:1
    00> 
    00> [721570078] <inf> auri: [01:05:18:571]..UartSleepInCallBack>>begin
    00> 
    00> [721570095] <inf> auri: [01:05:18:571]..UartMsgProc>>uart_sleep!
    00> 
    00> [721570108] <inf> auri: [01:05:18:572]..gps_is_working>>gps_is_on:0
    00> 
    00> [721570122] <inf> auri: [01:05:18:572]..MqttIsConnected>>mqtt_connected:0
    00> 
    00> [721570137] <inf> auri: [01:05:18:572]..nb_is_connecting>>nb_connecting_flag:0
    00> 
    00> [721570152] <inf> auri: [01:05:18:573]..wifi_is_working>>wifi_is_on:0
    00> 
    00> [721570513] <inf> auri: [01:05:18:584]..uart_sleep_in>>uart set low power success!
    00> 
    00> [770722047] <inf> auri: [01:30:18:570]..TauWakeUpUartCallBack>>begin
    00> 
    00> [770722064] <inf> auri: [01:30:18:570]..UartMsgProc>>uart_wake!
    00> 
    00> [770722409] <inf> auri: [01:30:18:581]..uart_sleep_out>>uart set active success!
    00> 
    00> [771693434] <inf> auri: [01:30:48:214]..modem_rsrp_handler>>rsrp_value:65
    00> 
    00> [771693516] <inf> auri: [01:30:48:217]..modem_rsrp_handler>>rsrp_value:255
    00> 
    00> [771705045] <inf> auri: [01:30:48:568]..NBRedrawSignal>>+CSCON: 0,0
    00> 
    00> 
    00> [771705060] <inf> auri: [01:30:48:569]..NBRedrawSignal>>mode:0
    00> 
    00> [771705073] <inf> auri: [01:30:48:569]..NBRedrawSignal>>reg stat:1
    00> 
    00> [780552478] <inf> auri: [01:35:18:571]..UartSleepInCallBack>>begin
    00> 
    00> [780552495] <inf> auri: [01:35:18:571]..UartMsgProc>>uart_sleep!
    00> 
    00> [780552508] <inf> auri: [01:35:18:572]..gps_is_working>>gps_is_on:0
    00> 
    00> [780552522] <inf> auri: [01:35:18:572]..MqttIsConnected>>mqtt_connected:0
    00> 
    00> [780552537] <inf> auri: [01:35:18:572]..nb_is_connecting>>nb_connecting_flag:0
    00> 
    00> [780552551] <inf> auri: [01:35:18:573]..wifi_is_working>>wifi_is_on:0
    00> 
    00> [780552912] <inf> auri: [01:35:18:584]..uart_sleep_in>>uart set low power success!
    00> 
    00> [802375979] <inf> auri: [01:46:24:571]..PMUMsgProcess>>alert
    00> 
    00> [802376001] <inf> auri: [01:46:24:572]..pmu_alert_proc>>status:00
    00> 
    00> [811901289] <inf> auri: [01:51:15:260]..PMUMsgProcess>>alert
    00> 
    00> [811901311] <inf> auri: [01:51:15:261]..pmu_alert_proc>>status:00
    00> 
    00> [813712208] <inf> auri: [01:52:10:525]..PMUMsgProcess>>alert
    00> 
    00> [813712231] <inf> auri: [01:52:10:526]..pmu_alert_proc>>status:00
    00> 
    00> [829704447] <inf> auri: [02:00:18:570]..TauWakeUpUartCallBack>>begin
    00> 
    00> [829704464] <inf> auri: [02:00:18:570]..UartMsgProc>>uart_wake!
    00> 
    00> [829704809] <inf> auri: [02:00:18:581]..uart_sleep_out>>uart set active success!
    00> 
    00> [830675834] <inf> auri: [02:00:48:214]..modem_rsrp_handler>>rsrp_value:64
    00> 
    00> [830676081] <inf> auri: [02:00:48:222]..modem_rsrp_handler>>rsrp_value:255
    00> 
    00> [830687445] <inf> auri: [02:00:48:568]..NBRedrawSignal>>+CSCON: 0,0
    00> 
    00> 
    00> [830687460] <inf> auri: [02:00:48:569]..NBRedrawSignal>>mode:0
    00> 
    00> [830687473] <inf> auri: [02:00:48:569]..NBRedrawSignal>>reg stat:1
    00> 
    00> [839534878] <inf> auri: [02:05:18:571]..UartSleepInCallBack>>begin
    00> 
    00> [839534895] <inf> auri: [02:05:18:571]..UartMsgProc>>uart_sleep!
    00> 
    00> [839534908] <inf> auri: [02:05:18:572]..gps_is_working>>gps_is_on:0
    00> 
    00> [839534922] <inf> auri: [02:05:18:572]..MqttIsConnected>>mqtt_connected:0
    00> 
    00> [839534937] <inf> auri: [02:05:18:572]..nb_is_connecting>>nb_connecting_flag:0
    00> 
    00> [839534952] <inf> auri: [02:05:18:573]..wifi_is_working>>wifi_is_on:0
    00> 
    00> [839535312] <inf> auri: [02:05:18:584]..uart_sleep_in>>uart set low power success!
    00> 
    00> [888686847] <inf> auri: [02:30:18:570]..TauWakeUpUartCallBack>>begin
    00> 
    00> [888686864] <inf> auri: [02:30:18:570]..UartMsgProc>>uart_wake!
    00> 
    00> [888687209] <inf> auri: [02:30:18:581]..uart_sleep_out>>uart set active success!
    00> 
    00> [889658234] <inf> auri: [02:30:48:214]..modem_rsrp_handler>>rsrp_value:66
    00> 
    00> [889658481] <inf> auri: [02:30:48:222]..modem_rsrp_handler>>rsrp_value:255
    00> 
    00> [889669845] <inf> auri: [02:30:48:568]..NBRedrawSignal>>+CSCON: 0,0
    00> 
    00> 
    00> [889669860] <inf> auri: [02:30:48:569]..NBRedrawSignal>>mode:0
    00> 
    00> [889669873] <inf> auri: [02:30:48:569]..NBRedrawSignal>>reg stat:1
    00> 
    00> [898517278] <inf> auri: [02:35:18:571]..UartSleepInCallBack>>begin
    00> 
    00> [898517294] <inf> auri: [02:35:18:571]..UartMsgProc>>uart_sleep!
    00> 
    00> [898517308] <inf> auri: [02:35:18:572]..gps_is_working>>gps_is_on:0
    00> 
    00> [898517322] <inf> auri: [02:35:18:572]..MqttIsConnected>>mqtt_connected:0
    00> 
    00> [898517337] <inf> auri: [02:35:18:572]..nb_is_connecting>>nb_connecting_flag:0
    00> 
    00> [898517351] <inf> auri: [02:35:18:573]..wifi_is_working>>wifi_is_on:0
    00> 
    00> [898517712] <inf> auri: [02:35:18:584]..uart_sleep_in>>uart set low power success!
    00> 
    00> [947669247] <inf> auri: [03:00:18:570]..TauWakeUpUartCallBack>>begin
    00> 
    00> [947669264] <inf> auri: [03:00:18:570]..UartMsgProc>>uart_wake!
    00> 
    00> [947669610] <inf> auri: [03:00:18:581]..uart_sleep_out>>uart set active success!
    00> 
    00> [948640634] <inf> auri: [03:00:48:214]..modem_rsrp_handler>>rsrp_value:64
    00> 
    00> [948641045] <inf> auri: [03:00:48:227]..modem_rsrp_handler>>rsrp_value:255
    00> 
    00> [948652245] <inf> auri: [03:00:48:568]..NBRedrawSignal>>+CSCON: 0,0
    00> 
    00> 
    00> [948652260] <inf> auri: [03:00:48:569]..NBRedrawSignal>>mode:0
    00> 
    00> [948652273] <inf> auri: [03:00:48:569]..NBRedrawSignal>>reg stat:1
    00> 
    00> [957499678] <inf> auri: [03:05:18:571]..UartSleepInCallBack>>begin
    00> 
    00> [957499695] <inf> auri: [03:05:18:571]..UartMsgProc>>uart_sleep!
    00> 
    00> [957499708] <inf> auri: [03:05:18:572]..gps_is_working>>gps_is_on:0
    00> 
    00> [957499722] <inf> auri: [03:05:18:572]..MqttIsConnected>>mqtt_connected:0
    00> 
    00> [957499737] <00> [957499737] <inf> auri: [03:05:18:572]..nb_is_connecting>>nb_connecting_flag:0
    00> 
    00> [957499752] <inf> auri: [03:05:18:573]..wifi_is_working>>wifi_is_on:0
    00> 
    00> [957500113] <inf> auri: [03:05:18:584]..uart_sleep_in>>uart set low power success!
    00> 
    00> [1006651647] <inf> auri: [03:30:18:570]..TauWakeUpUartCallBack>>begin
    00> 
    00> [1006651664] <inf> auri: [03:30:18:570]..UartMsgProc>>uart_wake!
    00> 
    00> [1006652008] <inf> auri: [03:30:18:581]..uart_sleep_out>>uart set active success!
    00> 
    00> [1007623034] <inf> auri: [03:30:48:214]..modem_rsrp_handler>>rsrp_value:64
    00> 
    00> [1007634645] <inf> auri: [03:30:48:568]..NBRedrawSignal>>+CSCON: 0,0
    00> 
    00> 
    00> [1007634660] <inf> auri: [03:30:48:569]..NBRedrawSignal>>mode:0
    00> 
    00> [1007634673] <inf> auri: [03:30:48:569]..NBRedrawSignal>>reg stat:1
    00> 
    00> [1009589120] <inf> auri: [03:31:48:214]..modem_rsrp_handler>>rsrp_value:255
    00> 
    00> [1009600725] <inf> auri: [03:31:48:568]..NBRedrawSignal>>+CSCON: 0,0
    00> 
    00> 
    00> [1009600740] <inf> auri: [03:31:48:569]..NBRedrawSignal>>mode:0
    00> 
    00> [1009600753] <inf> auri: [03:31:48:569]..NBRedrawSignal>>reg stat:1
    00> 
    00> [1016482078] <inf> auri: [03:35:18:571]..UartSleepInCallBack>>begin
    00> 
    00> [1016482095] <inf> auri: [03:35:18:571]..UartMsgProc>>uart_sleep!
    00> 
    00> [1016482109] <inf> auri: [03:35:18:572]..gps_is_working>>gps_is_on:0
    00> 
    00> [1016482123] <inf> auri: [03:35:18:572]..MqttIsConnected>>mqtt_connected:0
    00> 
    00> [1016482137] <inf> auri: [03:35:18:572]..nb_is_connecting>>nb_connecting_flag:0
    00> 
    00> [1016482152] <inf> auri: [03:35:18:573]..wifi_is_working>>wifi_is_on:0
    00> 
    00> [1016482513] <inf> auri: [03:35:18:584]..uart_sleep_in>>uart set low power success!
    00> 
    00> [1067600128] <inf> auri: [04:00:48:570]..TauWakeUpUartCallBack>>begin
    00> 

  • Hi,

     

    It looks like you're using JLink RTT for logging.

    This can be disrupted if a fault/reset occurs in the device, and thus lose the logging connection.

     

    When this issue occurs, can you try to attach a debug session and see where the device is stuck? Based on both the log output and current consumption; it looks like it is stuck somewhere.

     

    Kind regards,

    Håkon

Related