DA16200: rapid opening/closing of TCP sockets (with DPM enabled) sometimes fails

Jur 17 days ago

Opening and closing a TCP socket in a loop, with DPM sleeps in between each operation, fails.

The socket is created successfully, but deleting it (AT+TRTRM=1) returns an error (-745 CID invalid) as if the socket did not exist.
Program pseudocode:

// Init and setup:
// ATF, connect to AP, enter DPM sleep.

// Main loop:
// Toggle wake_up pin
+INIT:WAKEUP,EXT
AT+MCUWUDONE
AT+CLRDPMSLPEXT
AT+TRTC=xxxxx,yyyyyy
AT+SETDPMSLPEXT

// Toggle wake_up pin
+INIT:WAKEUP,EXT
AT+MCUWUDONE
AT+CLRDPMSLPEXT
AT+TRTRM=1
AT+SETDPMSLPEXT
// End of main loop

WORKAROUND HACK: If I surround the socket-related commands with a delay (e.g. 200 ms, before and after it), they will succeed and the error goes away.
However, this workaround is not reliable and fails within 10 iterations in my case. The problem is that the RTC_SENSOR pin never goes low, even though the command AT+SETDPMSLPEXT succeeds.

Is it a bug? Or are there some steps I am missing?

This is the DA16200 log, demonstrating the bug:

sockets_bug.log

Wakeup source is 0x81 
[2023-11-20 10:52:50.851] Initialize spi/sdio interrupt: 6
[2023-11-20 10:52:50.851] 
[2023-11-20 10:52:50.867] >>> Start DA16X Supplicant ...
[2023-11-20 10:52:50.883] >>> TIM STATUS: 0x00000010
[2023-11-20 10:52:50.884] >>> TIM : FAST
[2023-11-20 10:52:50.899] Waking up MCU ...
[2023-11-20 10:52:50.899] [atcmd_transport_set_max_session:9488]Set max session(8), max CID(9)
[2023-11-20 10:52:50.899] [atcmd_network_get_sess_info:10549]Load sess_info(len:1240, cnt:10)
[2023-11-20 10:52:50.915] [atcmd_transport_ssl_recover_context_dpm:13406]Not found tls context
[2023-11-20 10:52:50.916]  BUF Address = 0x916f8
[2023-11-20 10:52:50.916]  AT Address = 0xd61d0
[2023-11-20 10:52:50.916] [host_init] slave_rx_int_evt addr = 0xd82e0
[2023-11-20 10:52:50.916] [host_init] slave_cmd_int_evt addr = 0xd83d0
[2023-11-20 10:52:50.916] [atcmd_network_recover_session:10627]Start
[2023-11-20 10:52:50.916] [atcmd_network_recover_session:10767]After CID: 
[2023-11-20 10:52:50.931] argv[0]:AT+MCUWUDONE
[2023-11-20 10:52:50.931] 
[2023-11-20 10:52:50.931] 
[2023-11-20 10:52:50.931] Usage: AT+MCUWUDONE=
[2023-11-20 10:52:50.932] 
[2023-11-20 10:52:50.932] argv[0]:AT+CLRDPMSLPEXT
[2023-11-20 10:52:50.932] 
[2023-11-20 10:52:50.932] Usage: AT+CLRDPMSLPEXT=
[2023-11-20 10:52:50.932] 
[2023-11-20 10:52:50.932] argv[0]:ATF
[2023-11-20 10:52:50.932] 
[2023-11-20 10:52:50.932] 
[2023-11-20 10:52:51.683] 
[2023-11-20 10:52:51.683] Wakeup source is 0x0 
[2023-11-20 10:52:51.683] [dpm_init_retmemory] DPM INIT CONFIGURATION(1)
[2023-11-20 10:52:51.699] 
[2023-11-20 10:52:51.699] 
[2023-11-20 10:52:51.699] 	******************************************************
[2023-11-20 10:52:51.700] 	*             DA16200 SDK Information
[2023-11-20 10:52:51.700] 	* --------------------------------------------------- 
[2023-11-20 10:52:51.700] 	*
[2023-11-20 10:52:51.700] 	* - CPU Type        : Cortex-M4 (120MHz)
[2023-11-20 10:52:51.700] 	* - OS Type         : FreeRTOS 10.4.3
[2023-11-20 10:52:51.700] 	* - Serial Flash    : 4 MB
[2023-11-20 10:52:51.700] 	* - SDK Version     : V3.2.8.0 GEN-SPI
[2023-11-20 10:52:51.701] 	* - F/W Version     : FRTOS-GEN01-01-f017bfdf51-006558
[2023-11-20 10:52:51.715] 	* - F/W Build Time  : Nov 14 2023 15:08:14
[2023-11-20 10:52:51.716] 	* - Boot Index      : 1
[2023-11-20 10:52:51.716] 	*
[2023-11-20 10:52:51.716] 	******************************************************
[2023-11-20 10:52:51.716] 
[2023-11-20 10:52:51.716] Initialize spi/sdio interrupt: 6
[2023-11-20 10:52:51.716] 
[2023-11-20 10:52:51.747] 
[2023-11-20 10:52:51.747] System Mode : Station Only (0)
[2023-11-20 10:52:51.747] >>> Start DA16X Supplicant ...
[2023-11-20 10:52:51.763] >>> DA16x Supp Ver2.7 - 2022_03
[2023-11-20 10:52:51.763] >>> MAC address (sta0) : d4:3d:39:10:eb:b0
[2023-11-20 10:52:51.764] >>> sta0 interface add OK
[2023-11-20 10:52:51.764] >>> Start STA mode...
[2023-11-20 10:52:51.827] [atcmd_transport_set_max_session:9488]Set max session(8), max CID(9)
[2023-11-20 10:52:51.827] [atcmd_network_get_sess_info:10549]Load sess_info(len:1240, cnt:10)
[2023-11-20 10:52:51.828]  BUF Address = 0x916f8
[2023-11-20 10:52:51.843]  AT Address = 0xd2818
[2023-11-20 10:52:51.843] [host_init] slave_rx_int_evt addr = 0xd4900
[2023-11-20 10:52:51.843] [host_init] slave_cmd_int_evt addr = 0xd49f0
[2023-11-20 10:52:51.844] [atcmd_network_delete_sess_info:10603]Start
[2023-11-20 10:52:51.844] 
[2023-11-20 10:52:51.844] argv[0]:AT+VER
[2023-11-20 10:52:51.844] 
[2023-11-20 10:52:51.844] Usage: AT+VER=
[2023-11-20 10:52:51.844] 
[2023-11-20 10:52:51.844] argv[0]:A
[2023-11-20 10:52:51.923] Wakeup source is 0x1 
[2023-11-20 10:52:51.923] [dpm_init_retmemory] DPM INIT CONFIGURATION(1)
[2023-11-20 10:52:51.923] 
[2023-11-20 10:52:51.923] 
[2023-11-20 10:52:51.923] 	******************************************************
[2023-11-20 10:52:51.924] 	*             DA16200 SDK Information
[2023-11-20 10:52:51.924] 	* --------------------------------------------------- 
[2023-11-20 10:52:51.924] 	*
[2023-11-20 10:52:51.924] 	* - CPU Type        : Cortex-M4 (120MHz)
[2023-11-20 10:52:51.924] 	* - OS Type         : FreeRTOS 10.4.3
[2023-11-20 10:52:51.924] 	* - Serial Flash    : 4 MB
[2023-11-20 10:52:51.939] 	* - SDK Version     : V3.2.8.0 GEN-SPI
[2023-11-20 10:52:51.939] 	* - F/W Version     : FRTOS-GEN01-01-f017bfdf51-006558
[2023-11-20 10:52:51.940] 	* - F/W Build Time  : Nov 14 2023 15:08:14
[2023-11-20 10:52:51.940] 	* - Boot Index      : 1
[2023-11-20 10:52:51.940] 	*
[2023-11-20 10:52:51.940] 	******************************************************
[2023-11-20 10:52:51.940] 
[2023-11-20 10:52:51.940] Initialize spi/sdio interrupt: 6
[2023-11-20 10:52:51.940] 
[2023-11-20 10:52:51.971] 
[2023-11-20 10:52:51.971] System Mode : Station Only (0)
[2023-11-20 10:52:51.971] >>> Start DA16X Supplicant ...
[2023-11-20 10:52:51.987] >>> DA16x Supp Ver2.7 - 2022_03
[2023-11-20 10:52:51.987] >>> MAC address (sta0) : d4:3d:39:10:eb:b0
[2023-11-20 10:52:51.988] >>> sta0 interface add OK
[2023-11-20 10:52:51.988] >>> Start STA mode...
[2023-11-20 10:52:52.051] [atcmd_transport_set_max_session:9488]Set max session(8), max CID(9)
[2023-11-20 10:52:52.195] [atcmd_network_get_sess_info:10549]Load sess_info(len:1240, cnt:10)
[2023-11-20 10:52:52.355]  BUF Address = 0x916f8
[2023-11-20 10:52:52.355]  AT Address = 0xd2750
[2023-11-20 10:52:52.355] [host_init] slave_rx_int_evt addr = 0xd4838
[2023-11-20 10:52:52.356] [host_init] slave_cmd_int_evt addr = 0xd4928
[2023-11-20 10:52:52.356] [atcmd_network_delete_sess_info:10603]Start
[2023-11-20 10:52:52.356] 
[2023-11-20 10:52:52.356] argv[0]:AT+WFCC
[2023-11-20 10:52:52.356] argv[1]:SI
[2023-11-20 10:52:52.356] OK
[2023-11-20 10:52:52.356] 
[2023-11-20 10:52:52.356] Usage: AT+WFCC=<code>
[2023-11-20 10:52:52.356] 
[2023-11-20 10:52:52.356] argv[0]:AT+WFJAPA
[2023-11-20 10:52:52.356] argv[1]:AP_DA16200_dev
[2023-11-20 10:52:52.356] argv[2]:pass_da16200
[2023-11-20 10:52:52.371] FAIL
[2023-11-20 10:52:52.371] 0
[2023-11-20 10:52:52.371] 
[2023-11-20 10:52:53.155] 
[2023-11-20 10:52:53.155] >>> Network Interface (wlan0) : UP
[2023-11-20 10:52:53.155] >>> Associated with 7a:45:58:51:be:89
[2023-11-20 10:52:53.171] 
[2023-11-20 10:52:53.171] Connection COMPLETE to 7a:45:58:51:be:89
[2023-11-20 10:52:53.171] 
[2023-11-20 10:52:53.171] -- DHCP Client WLAN0: SEL(6)
[2023-11-20 10:52:53.171] 
[2023-11-20 10:52:53.171] ### User Call-back : Success to connect Wi-Fi ...
[2023-11-20 10:52:53.219] -- DHCP Client WLAN0: REQ(1)
[2023-11-20 10:52:53.939] -- DHCP Client WLAN0: CHK(8)
[2023-11-20 10:52:54.435] -- DHCP Client WLAN0: BOUND(10)
[2023-11-20 10:52:54.435] 	 Assigned addr   : 192.168.201.111
[2023-11-20 10:52:54.563] 	       netmask   : 255.255.255.0
[2023-11-20 10:52:54.627] 	       gateway   : 192.168.201.1
[2023-11-20 10:52:54.627] 	       DNS addr  : 192.168.201.1
[2023-11-20 10:52:54.627] 
[2023-11-20 10:52:54.627] 	 DHCP Server IP  : 192.168.201.1
[2023-11-20 10:52:54.643] 	 Lease Time      : 01h 46m 40s
[2023-11-20 10:52:54.643] 	 Renewal Time    : 00h 53m 20s
[2023-11-20 10:52:54.643] 
[2023-11-20 10:52:54.644] 
[2023-11-20 10:52:54.644] Usage: AT+WFJAPA=<ssid>[,<key>][,<hidden>]
[2023-11-20 10:52:54.644] 
[2023-11-20 10:52:54.644] argv[0]:AT+NWDNS
[2023-11-20 10:52:54.644] argv[1]:8.8.8.8
[2023-11-20 10:52:54.644] 
[2023-11-20 10:52:54.644] Usage: AT+NWDNS=<dns_ip>
[2023-11-20 10:52:54.644] 
[2023-11-20 10:52:54.644] argv[0]:AT+NWDNS2
[2023-11-20 10:52:54.644] argv[1]:89.212.166.246
[2023-11-20 10:52:54.644] 
[2023-11-20 10:52:54.644] Usage: AT+NWDNS2=<dns_2nd_ip>
[2023-11-20 10:52:54.644] 
[2023-11-20 10:52:54.644] argv[0]:AT+DPM
[2023-11-20 10:52:54.644] argv[1]:1
[2023-11-20 10:52:54.644] argv[2]:0
[2023-11-20 10:52:55.731] 
[2023-11-20 10:52:55.731] Wakeup source is 0x0 
[2023-11-20 10:52:55.747] [dpm_init_retmemory] DPM INIT CONFIGURATION(1)
[2023-11-20 10:52:55.747] 
[2023-11-20 10:52:55.747] 
[2023-11-20 10:52:55.747] 	******************************************************
[2023-11-20 10:52:55.748] 	*             DA16200 SDK Information
[2023-11-20 10:52:55.748] 	* --------------------------------------------------- 
[2023-11-20 10:52:55.748] 	*
[2023-11-20 10:52:55.748] 	* - CPU Type        : Cortex-M4 (120MHz)
[2023-11-20 10:52:55.748] 	* - OS Type         : FreeRTOS 10.4.3
[2023-11-20 10:52:55.748] 	* - Serial Flash    : 4 MB
[2023-11-20 10:52:55.748] 	* - SDK Version     : V3.2.8.0 GEN-SPI
[2023-11-20 10:52:55.763] 	* - F/W Version     : FRTOS-GEN01-01-f017bfdf51-006558
[2023-11-20 10:52:55.763] 	* - F/W Build Time  : Nov 14 2023 15:08:14
[2023-11-20 10:52:55.763] 	* - Boot Index      : 1
[2023-11-20 10:52:55.764] 	*
[2023-11-20 10:52:55.764] 	******************************************************
[2023-11-20 10:52:55.764] 
[2023-11-20 10:52:55.764] Initialize spi/sdio interrupt: 6
[2023-11-20 10:52:55.764] 
[2023-11-20 10:52:55.795] 
[2023-11-20 10:52:55.795] System Mode : Station Only (0)
[2023-11-20 10:52:55.795] >>> Start DA16X Supplicant ...
[2023-11-20 10:52:55.827] >>> DA16x Supp Ver2.7 - 2022_03
[2023-11-20 10:52:55.827] >>> Wi-Fi mode : b/g/n -> b/g (for DPM)
[2023-11-20 10:52:55.827] >>> MAC address (sta0) : d4:3d:39:10:eb:b0
[2023-11-20 10:52:55.828] >>> sta0 interface add OK
[2023-11-20 10:52:55.828] >>> Start STA mode...
[2023-11-20 10:52:55.907] [atcmd_transport_set_max_session:9488]Set max session(8), max CID(9)
[2023-11-20 10:52:55.907] [atcmd_network_get_sess_info:10549]Load sess_info(len:1240, cnt:10)
[2023-11-20 10:52:55.908] [atcmd_network_recover_context_nvram:10284]Start(sess_info_cnt:10)
[2023-11-20 10:52:55.908] [atcmd_transport_ssl_recover_context_nvram:12729]Start
[2023-11-20 10:52:55.923] [atcmd_transport_ssl_recover_context_nvram:12776]Invaild CID(0,60,-1)
[2023-11-20 10:52:55.923] [atcmd_transport_ssl_recover_context_nvram:12776]Invaild CID(0,61,-1)
[2023-11-20 10:52:55.924] [atcmd_transport_ssl_recover_context_nvram:12826]tls_nvr_cid_value[0]=-1, tls_nvr_role_value[0]=-1, tls_nvr_profile_value[0]=-1
[2023-11-20 10:52:55.924] [atcmd_transport_ssl_recover_context_nvram:12826]tls_nvr_cid_value[1]=-1, tls_nvr_role_value[1]=-1, tls_nvr_profile_value[1]=-1
[2023-11-20 10:52:55.940]  BUF Address = 0x916f8
[2023-11-20 10:52:55.940]  AT Address = 0xd4dc8
[2023-11-20 10:52:55.940] [host_init] slave_rx_int_evt addr = 0xd6ed8
[2023-11-20 10:52:55.940] [host_init] slave_cmd_int_evt addr = 0xd6fc8
[2023-11-20 10:52:55.940] [atcmd_network_recover_session:10627]Start
[2023-11-20 10:52:55.940] [atcmd_network_recover_session:10767]After CID: 
[2023-11-20 10:52:56.723] 
[2023-11-20 10:52:56.723] >>> Network Interface (wlan0) : UP
[2023-11-20 10:52:56.739] >>> Associated with 7a:45:58:51:be:89
[2023-11-20 10:52:56.739] 
[2023-11-20 10:52:56.739] Connection COMPLETE to 7a:45:58:51:be:89
[2023-11-20 10:52:56.756] 
[2023-11-20 10:52:56.756] -- DHCP Client WLAN0: SEL(6)
[2023-11-20 10:52:56.756] 
[2023-11-20 10:52:56.756] ### User Call-back : Success to connect Wi-Fi ...
[2023-11-20 10:52:57.523] -- DHCP Client WLAN0: REQ(1)
[2023-11-20 10:52:58.275] -- DHCP Client WLAN0: CHK(8)
[2023-11-20 10:52:58.771] -- DHCP Client WLAN0: BOUND(10)
[2023-11-20 10:52:58.787] 	 Assigned addr   : 192.168.201.111
[2023-11-20 10:52:58.787] 	       netmask   : 255.255.255.0
[2023-11-20 10:52:58.787] 	       gateway   : 192.168.201.1
[2023-11-20 10:52:58.787] 	       DNS addr  : 192.168.201.1
[2023-11-20 10:52:58.788] 
[2023-11-20 10:52:58.788] 	 DHCP Server IP  : 192.168.201.1
[2023-11-20 10:52:58.788] 	 Lease Time      : 01h 46m 40s
[2023-11-20 10:52:58.788] 	 Renewal Time    : 01h 28m 54s
[2023-11-20 10:52:58.788] 
[2023-11-20 10:52:58.788] >>> Start DPM Power-Down !!! 
[2023-11-20 10:52:58.788] [UMAC DPM] beaco�
[2023-11-20 10:52:58.819] Wakeup source is 0x81 
[2023-11-20 10:52:58.819] Initialize spi/sdio interrupt: 6
[2023-11-20 10:52:58.819] 
[2023-11-20 10:52:58.835] >>> Start DA16X Supplicant ...
[2023-11-20 10:52:58.851] >>> TIM STATUS: 0x00002000
[2023-11-20 10:52:58.851] >>> TIM : FULL
[2023-11-20 10:52:58.851] Waking up MCU ...
[2023-11-20 10:52:58.851] [atcmd_transport_set_max_session:9488]Set max session(8), max CID(9)
[2023-11-20 10:52:58.852] [atcmd_network_get_sess_info:10549]Load sess_info(len:1240, cnt:10)
[2023-11-20 10:52:58.867] [atcmd_transport_ssl_recover_context_dpm:13406]Not found tls context
[2023-11-20 10:52:58.867]  BUF Address = 0x916f8
[2023-11-20 10:52:58.883]  AT Address = 0xd6258
[2023-11-20 10:52:58.883] [host_init] slave_rx_int_evt addr = 0xd8308
[2023-11-20 10:52:58.883] [host_init] slave_cmd_int_evt addr = 0xd83f8
[2023-11-20 10:52:58.883] [atcmd_network_recover_session:10627]Start
[2023-11-20 10:52:58.884] [atcmd_network_recover_session:10767]After CID: 
[2023-11-20 10:52:58.884] argv[0]:AT+MCUWUDONE
[2023-11-20 10:52:58.884] 
[2023-11-20 10:52:58.884] 
[2023-11-20 10:52:58.884] Usage: AT+MCUWUDONE=
[2023-11-20 10:52:58.884] 
[2023-11-20 10:52:58.884] argv[0]:AT+CLRDPMSLPEXT
[2023-11-20 10:52:58.884] 
[2023-11-20 10:52:58.884] Usage: AT+CLRDPMSLPEXT=
[2023-11-20 10:52:58.884] 
[2023-11-20 10:52:58.884] argv[0]:AT+TRTC
[2023-11-20 10:52:58.884] argv[1]:192.168.101.33
[2023-11-20 10:52:58.884] argv[2]:11114
[2023-11-20 10:52:58.899] [atcmd_transport_create_context:9581]Before CID: 
[2023-11-20 10:52:58.899] [atcmd_transport_create_context:9587]sizeof(atcmd_sess_context):92
[2023-11-20 10:52:58.899] Created atcmd_sess_ctx_mutex
[2023-11-20 10:52:58.900] [atcmd_transport_alloc_context:9520]size info(atcmd_sess_context:92, type:1, ctx_size:28)
[2023-11-20 10:52:58.900] [atcmd_transport_create_context:9715]After CID: 1(1), 
[2023-11-20 10:52:58.900] [atcmd_network_connect_tcpc:11053]Start
[2023-11-20 10:52:58.900] 
[2023-11-20 10:52:58.900] Usage: AT+TRTC=<svr_ip>,<svr_port>(,<local_port>)
[2023-11-20 10:52:58.915] 
[2023-11-20 10:52:58.915] argv[0]:AT+SETDPMSLPEXT
[2023-11-20 10:52:58.915] 
[2023-11-20 10:52:58.915] Usage: AT+SETDPMSLPEXT=
[2023-11-20 10:52:59.059] >>> Start DPM Power-Down !!! 
[2023-11-20 10:52:59.061] PS TIME 240264�
[2023-11-20 10:52:59.093] Wakeup source is 0x81 
[2023-11-20 10:52:59.093] Initialize spi/sdio interrupt: 6
[2023-11-20 10:52:59.093] 
[2023-11-20 10:52:59.109] >>> Start DA16X Supplicant ...
[2023-11-20 10:52:59.125] >>> TIM STATUS: 0x00002000
[2023-11-20 10:52:59.125] >>> TIM : FULL
[2023-11-20 10:52:59.125] Waking up MCU ...
[2023-11-20 10:52:59.141] [atcmd_transport_set_max_session:9488]Set max session(8), max CID(9)
[2023-11-20 10:52:59.141] [atcmd_network_get_sess_info:10549]Load sess_info(len:1240, cnt:10)
[2023-11-20 10:52:59.142] [atcmd_transport_ssl_recover_context_dpm:13406]Not found tls context
[2023-11-20 10:52:59.157]  BUF Address = 0x916f8
[2023-11-20 10:52:59.157]  AT Address = 0xd61d0
[2023-11-20 10:52:59.157] [host_init] slave_rx_int_evt addr = 0xd82e0
[2023-11-20 10:52:59.158] [host_init] slave_cmd_int_evt addr = 0xd83d0
[2023-11-20 10:52:59.158] [atcmd_network_recover_session:10627]Start
[2023-11-20 10:52:59.158] 
[2023-11-20 10:52:59.158] argv[0]:AT+MCUWUDONE
[2023-11-20 10:52:59.158] [atcmd_network_display_sess_info:9912]Session info
[2023-11-20 10:52:59.173] * CID                  : 1
[2023-11-20 10:52:59.173] * Type                 : 1
[2023-11-20 10:52:59.173] * Local port           : 36935
[2023-11-20 10:52:59.173] * Peer IP Address      : 192.168.101.33:11114
[2023-11-20 10:52:59.174] 
[2023-11-20 10:52:59.174] Usage: AT+MCUWUDONE=
[2023-11-20 10:52:59.174] 
[2023-11-20 10:52:59.174] argv[0]:AT+CLRDPMSLPEXT
[2023-11-20 10:52:59.174] [atcmd_transport_alloc_context:9520]size info(atcmd_sess_context:92, type:1, ctx_size:28)
[2023-11-20 10:52:59.174] 
[2023-11-20 10:52:59.174] Usage: AT+CLRDPMSLPEXT=
[2023-11-20 10:52:59.174] [atcmd_network_connect_tcpc:11053]Start
[2023-11-20 10:52:59.174] 
[2023-11-20 10:52:59.174] argv[0]:AT+TRTRM
[2023-11-20 10:52:59.189] argv[1]:1
[2023-11-20 10:52:59.189] [atcmd_network_terminate_session:11506]cid(0x1)
[2023-11-20 10:52:59.189] [atcmd_network_terminate_session:11558]Not found cid(1)
[2023-11-20 10:52:59.189] [atcmd_network_recover_session:10767]After CID: 1(1), 
[2023-11-20 10:52:59.190] Usage: AT+TRTRM=<cid>[,<remote_ip>,<remote_port>]
[2023-11-20 10:52:59.190] 
[2023-11-20 10:52:59.190] 
[2023-11-20 10:52:59.190] argv[0]:AT+SETDPMSLPEXT
[2023-11-20 10:52:59.190] 
[2023-11-20 10:52:59.190] Usage: AT+SETDPMSLPEXT=
[2023-11-20 10:52:59.349] >>> Start DPM Power-Down !!! 
[2023-11-20 10:52:59.349] PS TIME 25019

...and with the workaround (added delays):

sockets_bug_workaround.log

�
Wakeup source is 0x81 
[2023-11-20 12:22:34.283] Initialize spi/sdio interrupt: 6
[2023-11-20 12:22:34.283] 
[2023-11-20 12:22:34.299] >>> Start DA16X Supplicant ...
[2023-11-20 12:22:34.315] >>> TIM STATUS: 0x00000010
[2023-11-20 12:22:34.315] >>> TIM : FAST
[2023-11-20 12:22:34.315] Waking up MCU ...
[2023-11-20 12:22:34.331] [atcmd_transport_set_max_session:9488]Set max session(8), max CID(9)
[2023-11-20 12:22:34.331] [atcmd_network_get_sess_info:10549]Load sess_info(len:1240, cnt:10)
[2023-11-20 12:22:34.347] [atcmd_transport_ssl_recover_context_dpm:13406]Not found tls context
[2023-11-20 12:22:34.348]  BUF Address = 0x916f8
[2023-11-20 12:22:34.348]  AT Address = 0xd61d0
[2023-11-20 12:22:34.348] [host_init] slave_rx_int_evt addr = 0xd82e0
[2023-11-20 12:22:34.348] [host_init] slave_cmd_int_evt addr = 0xd83d0
[2023-11-20 12:22:34.348] [atcmd_network_recover_session:10627]Start
[2023-11-20 12:22:34.348] 
[2023-11-20 12:22:34.348] argv[0]:AT+MCUWUDONE
[2023-11-20 12:22:34.348] [atcmd_network_recover_session:10767]After CID: 
[2023-11-20 12:22:34.363] Usage: AT+MCUWUDONE=
[2023-11-20 12:22:34.363] 
[2023-11-20 12:22:34.363] argv[0]:AT+CLRDPMSLPEXT
[2023-11-20 12:22:34.363] 
[2023-11-20 12:22:34.363] 
[2023-11-20 12:22:34.363] Usage: AT+CLRDPMSLPEXT=
[2023-11-20 12:22:34.363] 
[2023-11-20 12:22:34.363] argv[0]:ATF
[2023-11-20 12:22:34.363] 
[2023-11-20 12:22:34.363] 
[2023-11-20 12:22:35.115] 
[2023-11-20 12:22:35.115] Wakeup source is 0x0 
[2023-11-20 12:22:35.115] [dpm_init_retmemory] DPM INIT CONFIGURATION(1)
[2023-11-20 12:22:35.115] 
[2023-11-20 12:22:35.115] 
[2023-11-20 12:22:35.115] 	******************************************************
[2023-11-20 12:22:35.116] 	*             DA16200 SDK Information
[2023-11-20 12:22:35.116] 	* --------------------------------------------------- 
[2023-11-20 12:22:35.116] 	*
[2023-11-20 12:22:35.116] 	* - CPU Type        : Cortex-M4 (120MHz)
[2023-11-20 12:22:35.116] 	* - OS Type         : FreeRTOS 10.4.3
[2023-11-20 12:22:35.116] 	* - Serial Flash    : 4 MB
[2023-11-20 12:22:35.116] 	* - SDK Version     : V3.2.8.0 GEN-SPI
[2023-11-20 12:22:35.131] 	* - F/W Version     : FRTOS-GEN01-01-f017bfdf51-006558
[2023-11-20 12:22:35.131] 	* - F/W Build Time  : Nov 14 2023 15:08:14
[2023-11-20 12:22:35.132] 	* - Boot Index      : 1
[2023-11-20 12:22:35.132] 	*
[2023-11-20 12:22:35.132] 	******************************************************
[2023-11-20 12:22:35.132] 
[2023-11-20 12:22:35.132] Initialize spi/sdio interrupt: 6
[2023-11-20 12:22:35.132] 
[2023-11-20 12:22:35.163] 
[2023-11-20 12:22:35.163] System Mode : Station Only (0)
[2023-11-20 12:22:35.163] >>> Start DA16X Supplicant ...
[2023-11-20 12:22:35.179] >>> DA16x Supp Ver2.7 - 2022_03
[2023-11-20 12:22:35.179] >>> MAC address (sta0) : d4:3d:39:10:eb:b0
[2023-11-20 12:22:35.179] >>> sta0 interface add OK
[2023-11-20 12:22:35.179] >>> Start STA mode...
[2023-11-20 12:22:35.243] [atcmd_transport_set_max_session:9488]Set max session(8), max CID(9)
[2023-11-20 12:22:35.243] [atcmd_network_get_sess_info:10549]Load sess_info(len:1240, cnt:10)
[2023-11-20 12:22:35.259]  BUF Address = 0x916f8
[2023-11-20 12:22:35.259]  AT Address = 0xd2818
[2023-11-20 12:22:35.259] [host_init] slave_rx_int_evt addr = 0xd4900
[2023-11-20 12:22:35.259] [host_init] slave_cmd_int_evt addr = 0xd49f0
[2023-11-20 12:22:35.260] [atcmd_network_delete_sess_info:10603]Start
[2023-11-20 12:22:35.260] 
[2023-11-20 12:22:35.260] argv[0]:AT+VER
[2023-11-20 12:22:35.260] 
[2023-11-20 12:22:35.260] Usage: AT+VER=
[2023-11-20 12:22:35.260] 
[2023-11-20 12:22:35.260] argv[0]:
[2023-11-20 12:22:35.339] Wakeup source is 0x1 
[2023-11-20 12:22:35.339] [dpm_init_retmemory] DPM INIT CONFIGURATION(1)
[2023-11-20 12:22:35.339] 
[2023-11-20 12:22:35.339] 
[2023-11-20 12:22:35.339] 	******************************************************
[2023-11-20 12:22:35.340] 	*             DA16200 SDK Information
[2023-11-20 12:22:35.340] 	* --------------------------------------------------- 
[2023-11-20 12:22:35.340] 	*
[2023-11-20 12:22:35.340] 	* - CPU Type        : Cortex-M4 (120MHz)
[2023-11-20 12:22:35.340] 	* - OS Type         : FreeRTOS 10.4.3
[2023-11-20 12:22:35.355] 	* - Serial Flash    : 4 MB
[2023-11-20 12:22:35.355] 	* - SDK Version     : V3.2.8.0 GEN-SPI
[2023-11-20 12:22:35.355] 	* - F/W Version     : FRTOS-GEN01-01-f017bfdf51-006558
[2023-11-20 12:22:35.356] 	* - F/W Build Time  : Nov 14 2023 15:08:14
[2023-11-20 12:22:35.356] 	* - Boot Index      : 1
[2023-11-20 12:22:35.356] 	*
[2023-11-20 12:22:35.356] 	******************************************************
[2023-11-20 12:22:35.356] 
[2023-11-20 12:22:35.356] Initialize spi/sdio interrupt: 6
[2023-11-20 12:22:35.356] 
[2023-11-20 12:22:35.387] 
[2023-11-20 12:22:35.387] System Mode : Station Only (0)
[2023-11-20 12:22:35.387] >>> Start DA16X Supplicant ...
[2023-11-20 12:22:35.403] >>> DA16x Supp Ver2.7 - 2022_03
[2023-11-20 12:22:35.403] >>> MAC address (sta0) : d4:3d:39:10:eb:b0
[2023-11-20 12:22:35.403] >>> sta0 interface add OK
[2023-11-20 12:22:35.403] >>> Start STA mode...
[2023-11-20 12:22:35.467] [atcmd_transport_set_max_session:9488]Set max session(8), max CID(9)
[2023-11-20 12:22:35.611] [atcmd_network_get_sess_info:10549]Load sess_info(len:1240, cnt:10)
[2023-11-20 12:22:35.755]  BUF Address = 0x916f8
[2023-11-20 12:22:35.771]  AT Address = 0xd2750
[2023-11-20 12:22:35.771] [host_init] slave_rx_int_evt addr = 0xd4838
[2023-11-20 12:22:35.771] [host_init] slave_cmd_int_evt addr = 0xd4928
[2023-11-20 12:22:35.771] [atcmd_network_delete_sess_info:10603]Start
[2023-11-20 12:22:35.772] 
[2023-11-20 12:22:35.772] argv[0]:AT+WFCC
[2023-11-20 12:22:35.772] argv[1]:SI
[2023-11-20 12:22:35.772] OK
[2023-11-20 12:22:35.772] 
[2023-11-20 12:22:35.772] Usage: AT+WFCC=<code>
[2023-11-20 12:22:35.772] 
[2023-11-20 12:22:35.772] argv[0]:AT+WFJAPA
[2023-11-20 12:22:35.772] argv[1]:AP_DA16200_dev
[2023-11-20 12:22:35.772] argv[2]:pass_da16200
[2023-11-20 12:22:35.772] FAIL
[2023-11-20 12:22:35.772] 0
[2023-11-20 12:22:35.772] 
[2023-11-20 12:22:36.571] 
[2023-11-20 12:22:36.571] >>> Network Interface (wlan0) : UP
[2023-11-20 12:22:36.571] >>> Associated with 7a:45:58:51:be:89
[2023-11-20 12:22:36.587] 
[2023-11-20 12:22:36.587] Connection COMPLETE to 7a:45:58:51:be:89
[2023-11-20 12:22:36.587] 
[2023-11-20 12:22:36.587] -- DHCP Client WLAN0: SEL(6)
[2023-11-20 12:22:36.587] 
[2023-11-20 12:22:36.587] ### User Call-back : Success to connect Wi-Fi ...
[2023-11-20 12:22:36.619] -- DHCP Client WLAN0: REQ(1)
[2023-11-20 12:22:37.563] -- DHCP Client WLAN0: CHK(8)
[2023-11-20 12:22:38.123] -- DHCP Client WLAN0: BOUND(10)
[2023-11-20 12:22:38.123] 	 Assigned addr   : 192.168.201.111
[2023-11-20 12:22:38.251] 	       netmask   : 255.255.255.0
[2023-11-20 12:22:38.251] 	       gateway   : 192.168.201.1
[2023-11-20 12:22:38.251] 	       DNS addr  : 192.168.201.1
[2023-11-20 12:22:38.251] 
[2023-11-20 12:22:38.251] 	 DHCP Server IP  : 192.168.201.1
[2023-11-20 12:22:38.251] 	 Lease Time      : 01h 46m 40s
[2023-11-20 12:22:38.252] 	 Renewal Time    : 00h 53m 20s
[2023-11-20 12:22:38.252] 
[2023-11-20 12:22:38.252] 
[2023-11-20 12:22:38.252] Usage: AT+WFJAPA=<ssid>[,<key>][,<hidden>]
[2023-11-20 12:22:38.252] 
[2023-11-20 12:22:38.252] argv[0]:AT+NWDNS
[2023-11-20 12:22:38.252] argv[1]:8.8.8.8
[2023-11-20 12:22:38.267] 
[2023-11-20 12:22:38.267] Usage: AT+NWDNS=<dns_ip>
[2023-11-20 12:22:38.267] 
[2023-11-20 12:22:38.267] argv[0]:AT+NWDNS2
[2023-11-20 12:22:38.267] argv[1]:89.212.166.246
[2023-11-20 12:22:38.267] 
[2023-11-20 12:22:38.267] Usage: AT+NWDNS2=<dns_2nd_ip>
[2023-11-20 12:22:38.267] 
[2023-11-20 12:22:38.267] argv[0]:AT+DPM
[2023-11-20 12:22:38.268] argv[1]:1
[2023-11-20 12:22:38.268] argv[2]:0
[2023-11-20 12:22:39.339] 
[2023-11-20 12:22:39.339] Wakeup source is 0x0 
[2023-11-20 12:22:39.355] [dpm_init_retmemory] DPM INIT CONFIGURATION(1)
[2023-11-20 12:22:39.355] 
[2023-11-20 12:22:39.355] 
[2023-11-20 12:22:39.355] 	******************************************************
[2023-11-20 12:22:39.355] 	*             DA16200 SDK Information
[2023-11-20 12:22:39.356] 	* --------------------------------------------------- 
[2023-11-20 12:22:39.356] 	*
[2023-11-20 12:22:39.356] 	* - CPU Type        : Cortex-M4 (120MHz)
[2023-11-20 12:22:39.356] 	* - OS Type         : FreeRTOS 10.4.3
[2023-11-20 12:22:39.356] 	* - Serial Flash    : 4 MB
[2023-11-20 12:22:39.356] 	* - SDK Version     : V3.2.8.0 GEN-SPI
[2023-11-20 12:22:39.371] 	* - F/W Version     : FRTOS-GEN01-01-f017bfdf51-006558
[2023-11-20 12:22:39.371] 	* - F/W Build Time  : Nov 14 2023 15:08:14
[2023-11-20 12:22:39.371] 	* - Boot Index      : 1
[2023-11-20 12:22:39.372] 	*
[2023-11-20 12:22:39.372] 	******************************************************
[2023-11-20 12:22:39.372] 
[2023-11-20 12:22:39.372] Initialize spi/sdio interrupt: 6
[2023-11-20 12:22:39.372] 
[2023-11-20 12:22:39.403] 
[2023-11-20 12:22:39.403] System Mode : Station Only (0)
[2023-11-20 12:22:39.403] >>> Start DA16X Supplicant ...
[2023-11-20 12:22:39.435] >>> DA16x Supp Ver2.7 - 2022_03
[2023-11-20 12:22:39.435] >>> Wi-Fi mode : b/g/n -> b/g (for DPM)
[2023-11-20 12:22:39.435] >>> MAC address (sta0) : d4:3d:39:10:eb:b0
[2023-11-20 12:22:39.435] >>> sta0 interface add OK
[2023-11-20 12:22:39.436] >>> Start STA mode...
[2023-11-20 12:22:39.515] [atcmd_transport_set_max_session:9488]Set max session(8), max CID(9)
[2023-11-20 12:22:39.515] [atcmd_network_get_sess_info:10549]Load sess_info(len:1240, cnt:10)
[2023-11-20 12:22:39.516] [atcmd_network_recover_context_nvram:10284]Start(sess_info_cnt:10)
[2023-11-20 12:22:39.516] [atcmd_transport_ssl_recover_context_nvram:12729]Start
[2023-11-20 12:22:39.516] [atcmd_transport_ssl_recover_context_nvram:12776]Invaild CID(0,60,-1)
[2023-11-20 12:22:39.531] [atcmd_transport_ssl_recover_context_nvram:12776]Invaild CID(0,61,-1)
[2023-11-20 12:22:39.532] [atcmd_transport_ssl_recover_context_nvram:12826]tls_nvr_cid_value[0]=-1, tls_nvr_role_value[0]=-1, tls_nvr_profile_value[0]=-1
[2023-11-20 12:22:39.532] [atcmd_transport_ssl_recover_context_nvram:12826]tls_nvr_cid_value[1]=-1, tls_nvr_role_value[1]=-1, tls_nvr_profile_value[1]=-1
[2023-11-20 12:22:39.547]  BUF Address = 0x916f8
[2023-11-20 12:22:39.547]  AT Address = 0xd4dc8
[2023-11-20 12:22:39.547] [host_init] slave_rx_int_evt addr = 0xd6ed8
[2023-11-20 12:22:39.548] [host_init] slave_cmd_int_evt addr = 0xd6fc8
[2023-11-20 12:22:39.548] [atcmd_network_recover_session:10627]Start
[2023-11-20 12:22:39.548] [atcmd_network_recover_session:10767]After CID: 
[2023-11-20 12:22:40.347] 
[2023-11-20 12:22:40.347] >>> Network Interface (wlan0) : UP
[2023-11-20 12:22:40.347] >>> Associated with 7a:45:58:51:be:89
[2023-11-20 12:22:40.347] 
[2023-11-20 12:22:40.347] Connection COMPLETE to 7a:45:58:51:be:89
[2023-11-20 12:22:40.363] 
[2023-11-20 12:22:40.363] ### User Call-back : Success to connect Wi-Fi ...
[2023-11-20 12:22:40.363] 
[2023-11-20 12:22:40.363] -- DHCP Client WLAN0: SEL(6)
[2023-11-20 12:22:40.443] -- DHCP Client WLAN0: REQ(1)
[2023-11-20 12:22:41.291] -- DHCP Client WLAN0: CHK(8)
[2023-11-20 12:22:41.803] -- DHCP Client WLAN0: BOUND(10)
[2023-11-20 12:22:41.803] 	 Assigned addr   : 192.168.201.111
[2023-11-20 12:22:41.803] 	       netmask   : 255.255.255.0
[2023-11-20 12:22:41.803] 	       gateway   : 192.168.201.1
[2023-11-20 12:22:41.804] 	       DNS addr  : 192.168.201.1
[2023-11-20 12:22:41.804] 
[2023-11-20 12:22:41.804] 	 DHCP Server IP  : 192.168.201.1
[2023-11-20 12:22:41.804] 	 Lease Time      : 01h 46m 40s
[2023-11-20 12:22:41.804] 	 Renewal Time    : 01h 28m 54s
[2023-11-20 12:22:41.804] 
[2023-11-20 12:22:41.819] >>> Start DPM Power-Down !!! 
[2023-11-20 12:22:41.819] [UMAC DPM] beaco�
[2023-11-20 12:22:41.835] Wakeup source is 0x81 
[2023-11-20 12:22:41.835] Initialize spi/sdio interrupt: 6
[2023-11-20 12:22:41.835] 
[2023-11-20 12:22:41.851] >>> Start DA16X Supplicant ...
[2023-11-20 12:22:41.867] >>> TIM STATUS: 0x00002000
[2023-11-20 12:22:41.867] >>> TIM : FULL
[2023-11-20 12:22:41.867] Waking up MCU ...
[2023-11-20 12:22:41.883] [atcmd_transport_set_max_session:9488]Set max session(8), max CID(9)
[2023-11-20 12:22:41.883] [atcmd_network_get_sess_info:10549]Load sess_info(len:1240, cnt:10)
[2023-11-20 12:22:41.899] [atcmd_transport_ssl_recover_context_dpm:13406]Not found tls context
[2023-11-20 12:22:41.899]  BUF Address = 0x916f8
[2023-11-20 12:22:41.899]  AT Address = 0xd61d0
[2023-11-20 12:22:41.900] [host_init] slave_rx_int_evt addr = 0xd82e0
[2023-11-20 12:22:41.900] [host_init] slave_cmd_int_evt addr = 0xd83d0
[2023-11-20 12:22:41.900] [atcmd_network_recover_session:10627]Start
[2023-11-20 12:22:41.900] 
[2023-11-20 12:22:41.900] argv[0]:AT+MCUWUDONE
[2023-11-20 12:22:41.900] [atcmd_network_recover_session:10767]After CID: 
[2023-11-20 12:22:41.915] Usage: AT+MCUWUDONE=
[2023-11-20 12:22:41.915] 
[2023-11-20 12:22:41.915] 
[2023-11-20 12:22:41.915] argv[0]:AT+CLRDPMSLPEXT
[2023-11-20 12:22:41.915] 
[2023-11-20 12:22:41.915] Usage: AT+CLRDPMSLPEXT=
[2023-11-20 12:22:42.107] 
[2023-11-20 12:22:42.107] argv[0]:AT+TRTC
[2023-11-20 12:22:42.107] argv[1]:192.168.101.33
[2023-11-20 12:22:42.107] argv[2]:11114
[2023-11-20 12:22:42.107] [atcmd_transport_create_context:9581]Before CID: 
[2023-11-20 12:22:42.107] [atcmd_transport_create_context:9587]sizeof(atcmd_sess_context):92
[2023-11-20 12:22:42.108] Created atcmd_sess_ctx_mutex
[2023-11-20 12:22:42.108] [atcmd_transport_alloc_context:9520]size info(atcmd_sess_context:92, type:1, ctx_size:28)
[2023-11-20 12:22:42.123] [atcmd_transport_create_context:9715]After CID: 1(1), 
[2023-11-20 12:22:42.123] [atcmd_network_connect_tcpc:11053]Start
[2023-11-20 12:22:42.123] 
[2023-11-20 12:22:42.124] Usage: AT+TRTC=<svr_ip>,<svr_port>(,<local_port>)
[2023-11-20 12:22:42.315] 
[2023-11-20 12:22:42.315] argv[0]:AT+SETDPMSLPEXT
[2023-11-20 12:22:42.315] 
[2023-11-20 12:22:42.315] Usage: AT+SETDPMSLPEXT=
[2023-11-20 12:22:42.315] >>> Start DPM Power-Down !!! 
[2023-11-20 12:22:42.315] PS TIME 46996�
[2023-11-20 12:22:42.331] Wakeup source is 0x81 
[2023-11-20 12:22:42.347] Initialize spi/sdio interrupt: 6
[2023-11-20 12:22:42.347] 
[2023-11-20 12:22:42.347] >>> Start DA16X Supplicant ...
[2023-11-20 12:22:42.363] >>> TIM STATUS: 0x00002000
[2023-11-20 12:22:42.363] >>> TIM : FULL
[2023-11-20 12:22:42.379] Waking up MCU ...
[2023-11-20 12:22:42.379] [atcmd_transport_set_max_session:9488]Set max session(8), max CID(9)
[2023-11-20 12:22:42.379] [atcmd_network_get_sess_info:10549]Load sess_info(len:1240, cnt:10)
[2023-11-20 12:22:42.380] [atcmd_transport_ssl_recover_context_dpm:13406]Not found tls context
[2023-11-20 12:22:42.395]  BUF Address = 0x916f8
[2023-11-20 12:22:42.395]  AT Address = 0xd6258
[2023-11-20 12:22:42.395] [host_init] slave_rx_int_evt addr = 0xd8308
[2023-11-20 12:22:42.396] [host_init] slave_cmd_int_evt addr = 0xd83f8
[2023-11-20 12:22:42.396] [atcmd_network_recover_session:10627]Start
[2023-11-20 12:22:42.396] [atcmd_network_display_sess_info:9912]Session info
[2023-11-20 12:22:42.396] * CID                  : 1
[2023-11-20 12:22:42.396] * Type                 : 1
[2023-11-20 12:22:42.411] * Local port           : 59642
[2023-11-20 12:22:42.411] * Peer IP Address      : 192.168.101.33:11114
[2023-11-20 12:22:42.411] [atcmd_transport_alloc_context:9520]size info(atcmd_sess_context:92, type:1, ctx_size:28)
[2023-11-20 12:22:42.412] [atcmd_network_connect_tcpc:11053]Start
[2023-11-20 12:22:42.412] 
[2023-11-20 12:22:42.412] argv[0]:AT+MCUWUDONE
[2023-11-20 12:22:42.412] 
[2023-11-20 12:22:42.412] Usage: AT+MCUWUDONE=
[2023-11-20 12:22:42.412] 
[2023-11-20 12:22:42.412] argv[0]:AT+CLRDPMSLPEXT
[2023-11-20 12:22:42.412] 
[2023-11-20 12:22:42.412] Usage: AT+CLRDPMSLPEXT=
[2023-11-20 12:22:42.412] [atcmd_network_recover_session:10767]After CID: 1(1), 
[2023-11-20 12:22:42.587] 
[2023-11-20 12:22:42.587] argv[0]:AT+TRTRM
[2023-11-20 12:22:42.587] argv[1]:1
[2023-11-20 12:22:42.587] [atcmd_network_terminate_session:11506]cid(0x1)
[2023-11-20 12:22:42.587] [atcmd_transport_find_context:9739]Found cid(1) - type(1)
[2023-11-20 12:22:42.603] [atcmd_network_disconnect_tcpc:11135]Start(cid:1)
[2023-11-20 12:22:42.603] [atcmd_transport_find_context:9739]Found cid(1) - type(1)
[2023-11-20 12:22:42.699] [atcmd_transport_delete_context:9756]Before CID: 1(1), 
[2023-11-20 12:22:42.699] [atcmd_network_delete_sess_info:10603]Start
[2023-11-20 12:22:42.699] [atcmd_transport_delete_context:9799]After CID: 
[2023-11-20 12:22:42.700] 
[2023-11-20 12:22:42.700] Usage: AT+TRTRM=<cid>[,<remote_ip>,<remote_port>]
[2023-11-20 12:22:42.891] 
[2023-11-20 12:22:42.891] argv[0]:AT+SETDPMSLPEXT
[2023-11-20 12:22:42.891] 
[2023-11-20 12:22:42.891] Usage: AT+SETDPMSLPEXT=
[2023-11-20 12:22:42.891] >>> Start DPM Power-Down !!! 
[2023-11-20 12:22:42.907] PS TIME 5499
[2023-11-20 12:22:42.923] Wakeup source is 0x81 
[2023-11-20 12:22:42.923] Initialize spi/sdio interrupt: 6
[2023-11-20 12:22:42.923] 
[2023-11-20 12:22:42.939] >>> Start DA16X Supplicant ...
[2023-11-20 12:22:42.955] >>> TIM STATUS: 0x00002000
[2023-11-20 12:22:42.955] >>> TIM : FULL
[2023-11-20 12:22:42.955] Waking up MCU ...
[2023-11-20 12:22:42.971] [atcmd_transport_set_max_session:9488]Set max session(8), max CID(9)
[2023-11-20 12:22:42.971] [atcmd_network_get_sess_info:10549]Load sess_info(len:1240, cnt:10)
[2023-11-20 12:22:42.971] [atcmd_transport_ssl_recover_context_dpm:13406]Not found tls context
[2023-11-20 12:22:42.987]  BUF Address = 0x916f8
[2023-11-20 12:22:42.987]  AT Address = 0xd61d0
[2023-11-20 12:22:42.987] [host_init] slave_rx_int_evt addr = 0xd82e0
[2023-11-20 12:22:42.988] [host_init] slave_cmd_int_evt addr = 0xd83d0
[2023-11-20 12:22:42.988] [atcmd_network_recover_session:10627]Start
[2023-11-20 12:22:42.988] [atcmd_network_recover_session:10767]After CID: 
[2023-11-20 12:22:42.988] argv[0]:AT+MCUWUDONE
[2023-11-20 12:22:42.988] 
[2023-11-20 12:22:42.988] 
[2023-11-20 12:22:42.988] Usage: AT+MCUWUDONE=
[2023-11-20 12:22:42.988] 
[2023-11-20 12:22:42.988] argv[0]:AT+CLRDPMSLPEXT
[2023-11-20 12:22:42.988] 
[2023-11-20 12:22:42.988] Usage: AT+CLRDPMSLPEXT=
[2023-11-20 12:22:43.195] 
[2023-11-20 12:22:43.195] argv[0]:AT+TRTC
[2023-11-20 12:22:43.195] argv[1]:192.168.101.33
[2023-11-20 12:22:43.195] argv[2]:11114
[2023-11-20 12:22:43.195] [atcmd_transport_create_context:9581]Before CID: 
[2023-11-20 12:22:43.195] [atcmd_transport_create_context:9587]sizeof(atcmd_sess_context):92
[2023-11-20 12:22:43.196] Created atcmd_sess_ctx_mutex
[2023-11-20 12:22:43.196] [atcmd_transport_alloc_context:9520]size info(atcmd_sess_context:92, type:1, ctx_size:28)
[2023-11-20 12:22:43.196] [atcmd_transport_create_context:9715]After CID: 1(1), 
[2023-11-20 12:22:43.196] [atcmd_network_connect_tcpc:11053]Start
[2023-11-20 12:22:43.371] 
[2023-11-20 12:22:43.371] Usage: AT+TRTC=<svr_ip>,<svr_port>(,<local_port>)
[2023-11-20 12:22:43.579] 
[2023-11-20 12:22:43.579] argv[0]:AT+SETDPMSLPEXT
[2023-11-20 12:22:43.579] 
[2023-11-20 12:22:43.579] Usage: AT+SETDPMSLPEXT=
[2023-11-20 12:22:43.579] >>> Start DPM Power-Down !!! 
[2023-11-20 12:22:43.579] PS TIME 6500�
[2023-11-20 12:22:43.611] Wakeup source is 0x81 
[2023-11-20 12:22:43.611] Initialize spi/sdio interrupt: 6
[2023-11-20 12:22:43.611] 
[2023-11-20 12:22:43.627] >>> Start DA16X Supplicant ...
[2023-11-20 12:22:43.643] >>> TIM STATUS: 0x00002000
[2023-11-20 12:22:43.643] >>> TIM : FULL
[2023-11-20 12:22:43.643] Waking up MCU ...
[2023-11-20 12:22:43.659] [atcmd_transport_set_max_session:9488]Set max session(8), max CID(9)
[2023-11-20 12:22:43.659] [atcmd_network_get_sess_info:10549]Load sess_info(len:1240, cnt:10)
[2023-11-20 12:22:43.660] [atcmd_transport_ssl_recover_context_dpm:13406]Not found tls context
[2023-11-20 12:22:43.660]  BUF Address = 0x916f8
[2023-11-20 12:22:43.660]  AT Address = 0xd61d0
[2023-11-20 12:22:43.675] [host_init] slave_rx_int_evt addr = 0xd82e0
[2023-11-20 12:22:43.675] [host_init] slave_cmd_int_evt addr = 0xd83d0
[2023-11-20 12:22:43.675] [atcmd_network_recover_session:10627]Start
[2023-11-20 12:22:43.675] 
[2023-11-20 12:22:43.675] argv[0]:AT+MCUWUDONE
[2023-11-20 12:22:43.675] [atcmd_network_display_sess_info:9912]Session info
[2023-11-20 12:22:43.676] * CID                  : 1
[2023-11-20 12:22:43.676] * Type                 : 1
[2023-11-20 12:22:43.676] * Local port           : 56615
[2023-11-20 12:22:43.676] * Peer IP Address      : 192.168.101.33:11114
[2023-11-20 12:22:43.676] 
[2023-11-20 12:22:43.676] Usage: AT+MCUWUDONE=
[2023-11-20 12:22:43.691] 
[2023-11-20 12:22:43.691] argv[0]:AT+CLRDPMSLPEXT
[2023-11-20 12:22:43.691] [atcmd_transport_alloc_context:9520]size info(atcmd_sess_context:92, type:1, ctx_size:28)
[2023-11-20 12:22:43.692] 
[2023-11-20 12:22:43.692] Usage: AT+CLRDPMSLPEXT=
[2023-11-20 12:22:43.692] [atcmd_network_connect_tcpc:11053]Start
[2023-11-20 12:22:43.692] [atcmd_network_recover_session:10767]After CID: 1(1), 
[2023-11-20 12:22:43.867] 
[2023-11-20 12:22:43.867] argv[0]:AT+TRTRM
[2023-11-20 12:22:43.867] argv[1]:1
[2023-11-20 12:22:43.867] [atcmd_network_terminate_session:11506]cid(0x1)
[2023-11-20 12:22:43.867] [atcmd_transport_find_context:9739]Found cid(1) - type(1)
[2023-11-20 12:22:43.867] [atcmd_network_disconnect_tcpc:11135]Start(cid:1)
[2023-11-20 12:22:43.868] [atcmd_transport_find_context:9739]Found cid(1) - type(1)
[2023-11-20 12:22:43.963] [atcmd_transport_delete_context:9756]Before CID: 1(1), 
[2023-11-20 12:22:43.963] [atcmd_network_delete_sess_info:10603]Start
[2023-11-20 12:22:43.979] [atcmd_transport_delete_context:9799]After CID: 
[2023-11-20 12:22:43.979] 
[2023-11-20 12:22:43.979] Usage: AT+TRTRM=<cid>[,<remote_ip>,<remote_port>]
[2023-11-20 12:22:44.171] 
[2023-11-20 12:22:44.171] argv[0]:AT+SETDPMSLPEXT
[2023-11-20 12:22:44.171] 
[2023-11-20 12:22:44.171] Usage: AT+SETDPMSLPEXT=
[2023-11-20 12:22:44.571] >>> Start DPM Power-Down !!! 
[2023-11-20 12:22:44.571] PS TIME 960041 us 
[2023-11-20 12:22:44.587] Cancel RX�
[2023-11-20 12:22:44.603] Wakeup source is 0x81 
[2023-11-20 12:22:44.603] Initialize spi/sdio interrupt: 6
[2023-11-20 12:22:44.603] 
[2023-11-20 12:22:44.619] >>> Start DA16X Supplicant ...
[2023-11-20 12:22:44.635] >>> TIM STATUS: 0x00002000
[2023-11-20 12:22:44.635] >>> TIM : FULL
[2023-11-20 12:22:44.635] Waking up MCU ...
[2023-11-20 12:22:44.651] [atcmd_transport_set_max_session:9488]Set max session(8), max CID(9)
[2023-11-20 12:22:44.651] [atcmd_network_get_sess_info:10549]Load sess_info(len:1240, cnt:10)
[2023-11-20 12:22:44.667] [atcmd_transport_ssl_recover_context_dpm:13406]Not found tls context
[2023-11-20 12:22:44.667]  BUF Address = 0x916f8
[2023-11-20 12:22:44.667]  AT Address = 0xd61d0
[2023-11-20 12:22:44.667] [host_init] slave_rx_int_evt addr = 0xd82e0
[2023-11-20 12:22:44.668] [host_init] slave_cmd_int_evt addr = 0xd83d0
[2023-11-20 12:22:44.668] [atcmd_network_recover_session:10627]Start
[2023-11-20 12:22:44.668] [atcmd_network_recover_session:10767]After CID: 
[2023-11-20 12:22:44.668] argv[0]:AT+MCUWUDONE
[2023-11-20 12:22:44.668] 
[2023-11-20 12:22:44.668] 
[2023-11-20 12:22:44.668] Usage: AT+MCUWUDONE=
[2023-11-20 12:22:44.668] 
[2023-11-20 12:22:44.668] argv[0]:AT+CLRDPMSLPEXT
[2023-11-20 12:22:44.683] 
[2023-11-20 12:22:44.683] Usage: AT+CLRDPMSLPEXT=
[2023-11-20 12:22:44.875] 
[2023-11-20 12:22:44.875] argv[0]:AT+TRTC
[2023-11-20 12:22:44.875] argv[1]:192.168.101.33
[2023-11-20 12:22:44.875] argv[2]:11114
[2023-11-20 12:22:44.875] [atcmd_transport_create_context:9581]Before CID: 
[2023-11-20 12:22:44.875] [atcmd_transport_create_context:9587]sizeof(atcmd_sess_context):92
[2023-11-20 12:22:44.876] Created atcmd_sess_ctx_mutex
[2023-11-20 12:22:44.876] [atcmd_transport_alloc_context:9520]size info(atcmd_sess_context:92, type:1, ctx_size:28)
[2023-11-20 12:22:44.891] [atcmd_transport_create_context:9715]After CID: 1(1), 
[2023-11-20 12:22:44.891] [atcmd_network_connect_tcpc:11053]Start
[2023-11-20 12:22:44.891] 
[2023-11-20 12:22:44.891] Usage: AT+TRTC=<svr_ip>,<svr_port>(,<local_port>)
[2023-11-20 12:22:45.083] 
[2023-11-20 12:22:45.083] argv[0]:AT+SETDPMSLPEXT
[2023-11-20 12:22:45.083] 
[2023-11-20 12:22:45.083] Usage: AT+SETDPMSLPEXT=
[2023-11-20 12:22:45.083] >>> Start DPM Power-Down !!! 
[2023-11-20 12:22:45.083] PS TIME 4699�
[2023-11-20 12:22:45.099] Wakeup source is 0x81 
[2023-11-20 12:22:45.115] Initialize spi/sdio interrupt: 6
[2023-11-20 12:22:45.115] 
[2023-11-20 12:22:45.115] >>> Start DA16X Supplicant ...
[2023-11-20 12:22:45.131] >>> TIM STATUS: 0x00002000
[2023-11-20 12:22:45.131] >>> TIM : FULL
[2023-11-20 12:22:45.147] Waking up MCU ...
[2023-11-20 12:22:45.163] [atcmd_transport_set_max_session:9488]Set max session(8), max CID(9)
[2023-11-20 12:22:45.163] [atcmd_network_get_sess_info:10549]Load sess_info(len:1240, cnt:10)
[2023-11-20 12:22:45.163] [atcmd_transport_ssl_recover_context_dpm:13406]Not found tls context
[2023-11-20 12:22:45.164]  BUF Address = 0x916f8
[2023-11-20 12:22:45.164]  AT Address = 0xd61d0
[2023-11-20 12:22:45.164] [host_init] slave_rx_int_evt addr = 0xd82e0
[2023-11-20 12:22:45.164] [host_init] slave_cmd_int_evt addr = 0xd83d0
[2023-11-20 12:22:45.179] [atcmd_network_recover_session:10627]Start
[2023-11-20 12:22:45.179] [atcmd_network_display_sess_info:9912]Session info
[2023-11-20 12:22:45.179] * CID                  : 1
[2023-11-20 12:22:45.179] * Type                 : 1
[2023-11-20 12:22:45.180] * Local port           : 53588
[2023-11-20 12:22:45.180] * Peer IP Address      : 192.168.101.33:11114
[2023-11-20 12:22:45.180] 
[2023-11-20 12:22:45.180] argv[0]:AT+MCUWUDONE
[2023-11-20 12:22:45.180] [atcmd_transport_alloc_context:9520]size info(atcmd_sess_context:92, type:1, ctx_size:28)
[2023-11-20 12:22:45.195] 
[2023-11-20 12:22:45.195] Usage: AT+MCUWUDONE=
[2023-11-20 12:22:45.195] [atcmd_network_connect_tcpc:11053]Start
[2023-11-20 12:22:45.195] 
[2023-11-20 12:22:45.195] argv[0]:AT+CLRDPMSLPEXT
[2023-11-20 12:22:45.195] 
[2023-11-20 12:22:45.195] Usage: AT+CLRDPMSLPEXT=
[2023-11-20 12:22:45.195] [atcmd_network_recover_session:10767]After CID: 1(1), 
[2023-11-20 12:22:45.371] 
[2023-11-20 12:22:45.371] argv[0]:AT+TRTRM
[2023-11-20 12:22:45.371] argv[1]:1
[2023-11-20 12:22:45.371] [atcmd_network_terminate_session:11506]cid(0x1)
[2023-11-20 12:22:45.371] [atcmd_transport_find_context:9739]Found cid(1) - type(1)
[2023-11-20 12:22:45.371] [atcmd_network_disconnect_tcpc:11135]Start(cid:1)
[2023-11-20 12:22:45.371] [atcmd_transport_find_context:9739]Found cid(1) - type(1)
[2023-11-20 12:22:45.467] [atcmd_transport_delete_context:9756]Before CID: 1(1), 
[2023-11-20 12:22:45.467] [atcmd_network_delete_sess_info:10603]Start
[2023-11-20 12:22:45.467] [atcmd_transport_delete_context:9799]After CID: 
[2023-11-20 12:22:45.483] 
[2023-11-20 12:22:45.483] Usage: AT+TRTRM=<cid>[,<remote_ip>,<remote_port>]
[2023-11-20 12:22:45.675] 
[2023-11-20 12:22:45.675] argv[0]:AT+SETDPMSLPEXT
[2023-11-20 12:22:45.675] 
[2023-11-20 12:22:45.675] Usage: AT+SETDPMSLPEXT=
[2023-11-20 12:22:45.691] >>> Start DPM Power-Down !!! 
[2023-11-20 12:22:45.691] PS TIME 579986
[2023-11-20 12:22:45.723] Wakeup source is 0x81 
[2023-11-20 12:22:45.723] Initialize spi/sdio interrupt: 6
[2023-11-20 12:22:45.723] 
[2023-11-20 12:22:45.739] >>> Start DA16X Supplicant ...
[2023-11-20 12:22:45.755] >>> TIM STATUS: 0x00002000
[2023-11-20 12:22:45.755] >>> TIM : FULL
[2023-11-20 12:22:45.755] Waking up MCU ...
[2023-11-20 12:22:45.755] [atcmd_transport_set_max_session:9488]Set max session(8), max CID(9)
[2023-11-20 12:22:45.755] [atcmd_network_get_sess_info:10549]Load sess_info(len:1240, cnt:10)
[2023-11-20 12:22:45.771] [atcmd_transport_ssl_recover_context_dpm:13406]Not found tls context
[2023-11-20 12:22:45.771]  BUF Address = 0x916f8
[2023-11-20 12:22:45.771]  AT Address = 0xd6258
[2023-11-20 12:22:45.771] [host_init] slave_rx_int_evt addr = 0xd8308
[2023-11-20 12:22:45.772] [host_init] slave_cmd_int_evt addr = 0xd83f8
[2023-11-20 12:22:45.772] [atcmd_network_recover_session:10627]Start
[2023-11-20 12:22:45.772] 
[2023-11-20 12:22:45.772] argv[0]:AT+MCUWUDONE
[2023-11-20 12:22:45.772] [atcmd_network_recover_session:10767]After CID: 
[2023-11-20 12:22:45.772] Usage: AT+MCUWUDONE=
[2023-11-20 12:22:45.787] 
[2023-11-20 12:22:45.787] 
[2023-11-20 12:22:45.787] argv[0]:AT+CLRDPMSLPEXT
[2023-11-20 12:22:45.787] 
[2023-11-20 12:22:45.787] Usage: AT+CLRDPMSLPEXT=
[2023-11-20 12:22:45.979] 
[2023-11-20 12:22:45.979] argv[0]:AT+TRTC
[2023-11-20 12:22:45.979] argv[1]:192.168.101.33
[2023-11-20 12:22:45.979] argv[2]:11114
[2023-11-20 12:22:45.979] [atcmd_transport_create_context:9581]Before CID: 
[2023-11-20 12:22:45.979] [atcmd_transport_create_context:9587]sizeof(atcmd_sess_context):92
[2023-11-20 12:22:45.979] Created atcmd_sess_ctx_mutex
[2023-11-20 12:22:45.980] [atcmd_transport_alloc_context:9520]size info(atcmd_sess_context:92, type:1, ctx_size:28)
[2023-11-20 12:22:45.995] [atcmd_transport_create_context:9715]After CID: 1(1), 
[2023-11-20 12:22:45.995] [atcmd_network_connect_tcpc:11053]Start
[2023-11-20 12:22:45.995] 
[2023-11-20 12:22:45.995] Usage: AT+TRTC=<svr_ip>,<svr_port>(,<local_port>)
[2023-11-20 12:22:46.187] 
[2023-11-20 12:22:46.187] argv[0]:AT+SETDPMSLPEXT
[2023-11-20 12:22:46.187] 
[2023-11-20 12:22:46.187] Usage: AT+SETDPMSLPEXT=
[2023-11-20 12:22:46.187] >>> Start DPM Power-Down !!! 
[2023-11-20 12:22:46.187] PS TIME 4599
[2023-11-20 12:22:46.219] Wakeup source is 0x81 
[2023-11-20 12:22:46.219] Initialize spi/sdio interrupt: 6
[2023-11-20 12:22:46.219] 
[2023-11-20 12:22:46.235] >>> Start DA16X Supplicant ...
[2023-11-20 12:22:46.251] >>> TIM STATUS: 0x00002000
[2023-11-20 12:22:46.251] >>> TIM : FULL
[2023-11-20 12:22:46.251] Waking up MCU ...
[2023-11-20 12:22:46.267] [atcmd_transport_set_max_session:9488]Set max session(8), max CID(9)
[2023-11-20 12:22:46.267] [atcmd_network_get_sess_info:10549]Load sess_info(len:1240, cnt:10)
[2023-11-20 12:22:46.267] [atcmd_transport_ssl_recover_context_dpm:13406]Not found tls context
[2023-11-20 12:22:46.267]  BUF Address = 0x916f8
[2023-11-20 12:22:46.268]  AT Address = 0xd61d0
[2023-11-20 12:22:46.283] [host_init] slave_rx_int_evt addr = 0xd82e0
[2023-11-20 12:22:46.283] [host_init] slave_cmd_int_evt addr = 0xd83d0
[2023-11-20 12:22:46.283] [atcmd_network_recover_session:10627]Start
[2023-11-20 12:22:46.283] [atcmd_network_display_sess_info:9912]Session info
[2023-11-20 12:22:46.284] * CID                  : 1
[2023-11-20 12:22:46.284] * Type                 : 1
[2023-11-20 12:22:46.284] * Local port           : 47535
[2023-11-20 12:22:46.284] * Peer IP Address      : 192.168.101.33:11114
[2023-11-20 12:22:46.284] 
[2023-11-20 12:22:46.284] argv[0]:AT+MCUWUDONE
[2023-11-20 12:22:46.284] [atcmd_transport_alloc_context:9520]size info(atcmd_sess_context:92, type:1, ctx_size:28)
[2023-11-20 12:22:46.299] 
[2023-11-20 12:22:46.299] Usage: AT+MCUWUDONE=
[2023-11-20 12:22:46.299] 
[2023-11-20 12:22:46.299] argv[0]:AT+CLRDPMSLPEXT
[2023-11-20 12:22:46.299] [atcmd_network_connect_tcpc:11053]Start
[2023-11-20 12:22:46.300] 
[2023-11-20 12:22:46.300] Usage: AT+CLRDPMSLPEXT=
[2023-11-20 12:22:46.300] [atcmd_network_recover_session:10767]After CID: 1(1), 
[2023-11-20 12:22:46.475] 
[2023-11-20 12:22:46.475] argv[0]:AT+TRTRM
[2023-11-20 12:22:46.475] argv[1]:1
[2023-11-20 12:22:46.475] [atcmd_network_terminate_session:11506]cid(0x1)
[2023-11-20 12:22:46.475] [atcmd_transport_find_context:9739]Found cid(1) - type(1)
[2023-11-20 12:22:46.475] [atcmd_network_disconnect_tcpc:11135]Start(cid:1)
[2023-11-20 12:22:46.476] [atcmd_transport_find_context:9739]Found cid(1) - type(1)
[2023-11-20 12:22:46.571] [atcmd_transport_delete_context:9756]Before CID: 1(1), 
[2023-11-20 12:22:46.571] [atcmd_network_delete_sess_info:10603]Start
[2023-11-20 12:22:46.587] [atcmd_transport_delete_context:9799]After CID: 
[2023-11-20 12:22:46.587] 
[2023-11-20 12:22:46.587] Usage: AT+TRTRM=<cid>[,<remote_ip>,<remote_port>]
[2023-11-20 12:22:46.776] 
[2023-11-20 12:22:46.776] argv[0]:AT+SETDPMSLPEXT
[2023-11-20 12:22:46.776] 
[2023-11-20 12:22:46.776] Usage: AT+SETDPMSLPEXT=
[2023-11-20 12:22:46.776] >>> Start DPM Power-Down !!! 
[2023-11-20 12:22:46.776] PS TIME 560007
[2023-11-20 12:22:46.807] Wakeup source is 0x81 
[2023-11-20 12:22:46.807] Initialize spi/sdio interrupt: 6
[2023-11-20 12:22:46.807] 
[2023-11-20 12:22:46.823] >>> Start DA16X Supplicant ...
[2023-11-20 12:22:46.839] >>> TIM STATUS: 0x00002000
[2023-11-20 12:22:46.839] >>> TIM : FULL
[2023-11-20 12:22:46.839] Waking up MCU ...
[2023-11-20 12:22:46.855] [atcmd_transport_set_max_session:9488]Set max session(8), max CID(9)
[2023-11-20 12:22:46.855] [atcmd_network_get_sess_info:10549]Load sess_info(len:1240, cnt:10)
[2023-11-20 12:22:46.871] [atcmd_transport_ssl_recover_context_dpm:13406]Not found tls context
[2023-11-20 12:22:46.871]  BUF Address = 0x916f8
[2023-11-20 12:22:46.871]  AT Address = 0xd61d0
[2023-11-20 12:22:46.871] [host_init] slave_rx_int_evt addr = 0xd82e0
[2023-11-20 12:22:46.873] [host_init] slave_cmd_int_evt addr = 0xd83d0
[2023-11-20 12:22:46.873] [atcmd_network_recover_session:10627]Start
[2023-11-20 12:22:46.873] 
[2023-11-20 12:22:46.873] argv[0]:AT+MCUWUDONE
[2023-11-20 12:22:46.873] [atcmd_network_recover_session:10767]After CID: 
[2023-11-20 12:22:46.873] Usage: AT+MCUWUDONE=
[2023-11-20 12:22:46.873] 
[2023-11-20 12:22:46.873] 
[2023-11-20 12:22:46.887] argv[0]:AT+CLRDPMSLPEXT
[2023-11-20 12:22:46.887] 
[2023-11-20 12:22:46.887] Usage: AT+CLRDPMSLPEXT=
[2023-11-20 12:22:47.079] 
[2023-11-20 12:22:47.079] argv[0]:AT+TRTC
[2023-11-20 12:22:47.079] argv[1]:192.168.101.33
[2023-11-20 12:22:47.079] argv[2]:11114
[2023-11-20 12:22:47.079] [atcmd_transport_create_context:9581]Before CID: 
[2023-11-20 12:22:47.079] [atcmd_transport_create_context:9587]sizeof(atcmd_sess_context):92
[2023-11-20 12:22:47.079] Created atcmd_sess_ctx_mutex
[2023-11-20 12:22:47.080] [atcmd_transport_alloc_context:9520]size info(atcmd_sess_context:92, type:1, ctx_size:28)
[2023-11-20 12:22:47.080] [atcmd_transport_create_context:9715]After CID: 1(1), 
[2023-11-20 12:22:47.095] [atcmd_network_connect_tcpc:11053]Start
[2023-11-20 12:22:47.095] 
[2023-11-20 12:22:47.095] Usage: AT+TRTC=<svr_ip>,<svr_port>(,<local_port>)
[2023-11-20 12:22:47.271] 
[2023-11-20 12:22:47.271] argv[0]:AT+SETDPMSLPEXT
[2023-11-20 12:22:47.271] 
[2023-11-20 12:22:47.287] Usage: AT+SETDPMSLPEXT=
[2023-11-20 12:22:47.287] >>> Start DPM Power-Down !!! 
[2023-11-20 12:22:47.287] PS TIME 4699�
[2023-11-20 12:22:47.303] Wakeup source is 0x81 
[2023-11-20 12:22:47.303] Initialize spi/sdio interrupt: 6
[2023-11-20 12:22:47.303] 
[2023-11-20 12:22:47.319] >>> Start DA16X Supplicant ...
[2023-11-20 12:22:47.335] >>> TIM STATUS: 0x00002000
[2023-11-20 12:22:47.335] >>> TIM : FULL
[2023-11-20 12:22:47.351] Waking up MCU ...
[2023-11-20 12:22:47.351] [atcmd_transport_set_max_session:9488]Set max session(8), max CID(9)
[2023-11-20 12:22:47.367] [atcmd_network_get_sess_info:10549]Load sess_info(len:1240, cnt:10)
[2023-11-20 12:22:47.367] [atcmd_transport_ssl_recover_context_dpm:13406]Not found tls context
[2023-11-20 12:22:47.368]  BUF Address = 0x916f8
[2023-11-20 12:22:47.368]  AT Address = 0xd61d0
[2023-11-20 12:22:47.368] [host_init] slave_rx_int_evt addr = 0xd82e0
[2023-11-20 12:22:47.368] [host_init] slave_cmd_int_evt addr = 0xd83d0
[2023-11-20 12:22:47.368] [atcmd_network_recover_session:10627]Start
[2023-11-20 12:22:47.383] 
[2023-11-20 12:22:47.383] argv[0]:AT+MCUWUDONE
[2023-11-20 12:22:47.383] [atcmd_network_display_sess_info:9912]Session info
[2023-11-20 12:22:47.383] * CID                  : 1
[2023-11-20 12:22:47.383] * Type                 : 1
[2023-11-20 12:22:47.383] * Local port           : 44508
[2023-11-20 12:22:47.384] * Peer IP Address      : 192.168.101.33:11114
[2023-11-20 12:22:47.384] 
[2023-11-20 12:22:47.384] Usage: AT+MCUWUDONE=
[2023-11-20 12:22:47.384] 
[2023-11-20 12:22:47.384] argv[0]:AT+CLRDPMSLPEXT
[2023-11-20 12:22:47.384] [atcmd_transport_alloc_context:9520]size info(atcmd_sess_context:92, type:1, ctx_size:28)
[2023-11-20 12:22:47.399] 
[2023-11-20 12:22:47.399] Usage: AT+CLRDPMSLPEXT=
[2023-11-20 12:22:47.399] [atcmd_network_connect_tcpc:11053]Start
[2023-11-20 12:22:47.399] [atcmd_network_recover_session:10767]After CID: 1(1), 
[2023-11-20 12:22:47.575] 
[2023-11-20 12:22:47.575] argv[0]:AT+TRTRM
[2023-11-20 12:22:47.575] argv[1]:1
[2023-11-20 12:22:47.575] [atcmd_network_terminate_session:11506]cid(0x1)
[2023-11-20 12:22:47.575] [atcmd_transport_find_context:9739]Found cid(1) - type(1)
[2023-11-20 12:22:47.575] [atcmd_network_disconnect_tcpc:11135]Start(cid:1)
[2023-11-20 12:22:47.575] [atcmd_transport_find_context:9739]Found cid(1) - type(1)
[2023-11-20 12:22:47.671] [atcmd_transport_delete_context:9756]Before CID: 1(1), 
[2023-11-20 12:22:47.671] [atcmd_network_delete_sess_info:10603]Start
[2023-11-20 12:22:47.671] [atcmd_transport_delete_context:9799]After CID: 
[2023-11-20 12:22:47.687] 
[2023-11-20 12:22:47.687] Usage: AT+TRTRM=<cid>[,<remote_ip>,<remote_port>]
[2023-11-20 12:22:47.879] 
[2023-11-20 12:22:47.879] argv[0]:AT+SETDPMSLPEXT
[2023-11-20 12:22:47.879] 
[2023-11-20 12:22:47.879] Usage: AT+SETDPMSLPEXT=
[2023-11-20 12:22:47.879] >>> Start DPM Power-Down !!! 
[2023-11-20 12:22:47.879] PS TIME 5600�
[2023-11-20 12:22:47.895] Wakeup source is 0x81 
[2023-11-20 12:22:47.895] Initialize spi/sdio interrupt: 6
[2023-11-20 12:22:47.911] 
[2023-11-20 12:22:47.911] >>> Start DA16X Supplicant ...
[2023-11-20 12:22:47.927] >>> TIM STATUS: 0x00002000
[2023-11-20 12:22:47.927] >>> TIM : FULL
[2023-11-20 12:22:47.943] Waking up MCU ...
[2023-11-20 12:22:47.943] [atcmd_transport_set_max_session:9488]Set max session(8), max CID(9)
[2023-11-20 12:22:47.959] [atcmd_network_get_sess_info:10549]Load sess_info(len:1240, cnt:10)
[2023-11-20 12:22:47.959] [atcmd_transport_ssl_recover_context_dpm:13406]Not found tls context
[2023-11-20 12:22:47.959]  BUF Address = 0x916f8
[2023-11-20 12:22:47.960]  AT Address = 0xd61d0
[2023-11-20 12:22:47.960] [host_init] slave_rx_int_evt addr = 0xd82e0
[2023-11-20 12:22:47.960] [host_init] slave_cmd_int_evt addr = 0xd83d0
[2023-11-20 12:22:47.975] [atcmd_network_recover_session:10627]Start
[2023-11-20 12:22:47.975] [atcmd_network_recover_session:10767]After CID: 
[2023-11-20 12:22:47.975] argv[0]:AT+MCUWUDONE
[2023-11-20 12:22:47.975] 
[2023-11-20 12:22:47.975] 
[2023-11-20 12:22:47.975] Usage: AT+MCUWUDONE=
[2023-11-20 12:22:47.975] 
[2023-11-20 12:22:47.975] argv[0]:AT+CLRDPMSLPEXT
[2023-11-20 12:22:47.976] 
[2023-11-20 12:22:47.976] Usage: AT+CLRDPMSLPEXT=
[2023-11-20 12:22:48.167] 
[2023-11-20 12:22:48.167] argv[0]:AT+TRTC
[2023-11-20 12:22:48.167] argv[1]:192.168.101.33
[2023-11-20 12:22:48.167] argv[2]:11114
[2023-11-20 12:22:48.167] [atcmd_transport_create_context:9581]Before CID: 
[2023-11-20 12:22:48.167] [atcmd_transport_create_context:9587]sizeof(atcmd_sess_context):92
[2023-11-20 12:22:48.183] Created atcmd_sess_ctx_mutex
[2023-11-20 12:22:48.183] [atcmd_transport_alloc_context:9520]size info(atcmd_sess_context:92, type:1, ctx_size:28)
[2023-11-20 12:22:48.183] [atcmd_transport_create_context:9715]After CID: 1(1), 
[2023-11-20 12:22:48.184] [atcmd_network_connect_tcpc:11053]Start
[2023-11-20 12:22:48.184] 
[2023-11-20 12:22:48.184] Usage: AT+TRTC=<svr_ip>,<svr_port>(,<local_port>)
[2023-11-20 12:22:48.375] 
[2023-11-20 12:22:48.375] argv[0]:AT+SETDPMSLPEXT
[2023-11-20 12:22:48.375] 
[2023-11-20 12:22:48.375] Usage: AT+SETDPMSLPEXT=
[2023-11-20 12:22:48.375] >>> Start DPM Power-Down !!! 
[2023-11-20 12:22:48.375] PS TIME 47003�
[2023-11-20 12:22:48.407] Wakeup source is 0x81 
[2023-11-20 12:22:48.407] Initialize spi/sdio interrupt: 6
[2023-11-20 12:22:48.407] 
[2023-11-20 12:22:48.423] >>> Start DA16X Supplicant ...
[2023-11-20 12:22:48.439] >>> TIM STATUS: 0x00002000
[2023-11-20 12:22:48.439] >>> TIM : FULL
[2023-11-20 12:22:48.439] Waking up MCU ...
[2023-11-20 12:22:48.455] [atcmd_transport_set_max_session:9488]Set max session(8), max CID(9)
[2023-11-20 12:22:48.455] [atcmd_network_get_sess_info:10549]Load sess_info(len:1240, cnt:10)
[2023-11-20 12:22:48.455] [atcmd_transport_ssl_recover_context_dpm:13406]Not found tls context
[2023-11-20 12:22:48.471]  BUF Address = 0x916f8
[2023-11-20 12:22:48.471]  AT Address = 0xd61d0
[2023-11-20 12:22:48.471] [host_init] slave_rx_int_evt addr = 0xd82e0
[2023-11-20 12:22:48.472] [host_init] slave_cmd_int_evt addr = 0xd83d0
[2023-11-20 12:22:48.472] [atcmd_network_recover_session:10627]Start
[2023-11-20 12:22:48.472] [atcmd_network_display_sess_info:9912]Session info
[2023-11-20 12:22:48.472] * CID                  : 1
[2023-11-20 12:22:48.472] * Type                 : 1
[2023-11-20 12:22:48.472] * Local port           : 41481
[2023-11-20 12:22:48.472] * Peer IP Address      : 192.168.101.33:11114
[2023-11-20 12:22:48.487] 
[2023-11-20 12:22:48.487] argv[0]:AT+MCUWUDONE
[2023-11-20 12:22:48.487] [atcmd_transport_alloc_context:9520]size info(atcmd_sess_context:92, type:1, ctx_size:28)
[2023-11-20 12:22:48.487] 
[2023-11-20 12:22:48.487] Usage: AT+MCUWUDONE=
[2023-11-20 12:22:48.487] 
[2023-11-20 12:22:48.487] argv[0]:AT+CLRDPMSLPEXT
[2023-11-20 12:22:48.488] [atcmd_network_connect_tcpc:11053]Start
[2023-11-20 12:22:48.488] 
[2023-11-20 12:22:48.488] Usage: AT+CLRDPMSLPEXT=
[2023-11-20 12:22:48.488] [atcmd_network_recover_session:10767]After CID: 1(1), 
[2023-11-20 12:22:48.663] 
[2023-11-20 12:22:48.663] argv[0]:AT+TRTRM
[2023-11-20 12:22:48.663] argv[1]:1
[2023-11-20 12:22:48.663] [atcmd_network_terminate_session:11506]cid(0x1)
[2023-11-20 12:22:48.663] [atcmd_transport_find_context:9739]Found cid(1) - type(1)
[2023-11-20 12:22:48.679] [atcmd_network_disconnect_tcpc:11135]Start(cid:1)
[2023-11-20 12:22:48.679] [atcmd_transport_find_context:9739]Found cid(1) - type(1)
[2023-11-20 12:22:48.775] [atcmd_transport_delete_context:9756]Before CID: 1(1), 
[2023-11-20 12:22:48.775] [atcmd_network_delete_sess_info:10603]Start
[2023-11-20 12:22:48.775] [atcmd_transport_delete_context:9799]After CID: 
[2023-11-20 12:22:48.775] 
[2023-11-20 12:22:48.775] Usage: AT+TRTRM=<cid>[,<remote_ip>,<remote_port>]
[2023-11-20 12:22:48.967] 
[2023-11-20 12:22:48.967] argv[0]:AT+SETDPMSLPEXT
[2023-11-20 12:22:48.967] 
[2023-11-20 12:22:48.967] Usage: AT+SETDPMSLPEXT=
[2023-11-20 12:22:48.967] >>> Start DPM Power-Down !!! 
[2023-11-20 12:22:48.983] PS TIME 559
[2023-11-20 12:22:48.999] Wakeup source is 0x81 
[2023-11-20 12:22:48.999] Initialize spi/sdio interrupt: 6
[2023-11-20 12:22:48.999] 
[2023-11-20 12:22:49.015] >>> Start DA16X Supplicant ...
[2023-11-20 12:22:49.031] >>> TIM STATUS: 0x00002000
[2023-11-20 12:22:49.031] >>> TIM : FULL
[2023-11-20 12:22:49.031] Waking up MCU ...
[2023-11-20 12:22:49.047] [atcmd_transport_set_max_session:9488]Set max session(8), max CID(9)
[2023-11-20 12:22:49.047] [atcmd_network_get_sess_info:10549]Load sess_info(len:1240, cnt:10)
[2023-11-20 12:22:49.047] [atcmd_transport_ssl_recover_context_dpm:13406]Not found tls context
[2023-11-20 12:22:49.063]  BUF Address = 0x916f8
[2023-11-20 12:22:49.063]  AT Address = 0xd61d0
[2023-11-20 12:22:49.063] [host_init] slave_rx_int_evt addr = 0xd82e0
[2023-11-20 12:22:49.063] [host_init] slave_cmd_int_evt addr = 0xd83d0
[2023-11-20 12:22:49.064] [atcmd_network_recover_session:10627]Start
[2023-11-20 12:22:49.064] [atcmd_network_recover_session:10767]After CID: 
[2023-11-20 12:22:49.064] argv[0]:AT+MCUWUDONE
[2023-11-20 12:22:49.064] 
[2023-11-20 12:22:49.064] 
[2023-11-20 12:22:49.064] Usage: AT+MCUWUDONE=
[2023-11-20 12:22:49.064] 
[2023-11-20 12:22:49.064] argv[0]:AT+CLRDPMSLPEXT
[2023-11-20 12:22:49.079] 
[2023-11-20 12:22:49.079] Usage: AT+CLRDPMSLPEXT=
[2023-11-20 12:22:49.271] 
[2023-11-20 12:22:49.271] argv[0]:AT+TRTC
[2023-11-20 12:22:49.271] argv[1]:192.168.101.33
[2023-11-20 12:22:49.271] argv[2]:11114
[2023-11-20 12:22:49.271] [atcmd_transport_create_context:9581]Before CID: 
[2023-11-20 12:22:49.271] [atcmd_transport_create_context:9587]sizeof(atcmd_sess_context):92
[2023-11-20 12:22:49.271] Created atcmd_sess_ctx_mutex
[2023-11-20 12:22:49.272] [atcmd_transport_alloc_context:9520]size info(atcmd_sess_context:92, type:1, ctx_size:28)
[2023-11-20 12:22:49.272] [atcmd_transport_create_context:9715]After CID: 1(1), 
[2023-11-20 12:22:49.288] [atcmd_network_connect_tcpc:11053]Start
[2023-11-20 12:22:49.288] 
[2023-11-20 12:22:49.288] Usage: AT+TRTC=<svr_ip>,<svr_port>(,<local_port>)
[2023-11-20 12:22:49.463] 
[2023-11-20 12:22:49.463] argv[0]:AT+SETDPMSLPEXT
[2023-11-20 12:22:49.463] 
[2023-11-20 12:22:49.471] Usage: AT+SETDPMSLPEXT=
[2023-11-20 12:22:49.471] >>> Start DPM Power-Down !!! 
[2023-11-20 12:22:49.472] PS TIME 46994�
[2023-11-20 12:22:49.503] Wakeup source is 0x81 
[2023-11-20 12:22:49.503] Initialize spi/sdio interrupt: 6
[2023-11-20 12:22:49.503] 
[2023-11-20 12:22:49.519] >>> Start DA16X Supplicant ...
[2023-11-20 12:22:49.535] >>> TIM STATUS: 0x00002000
[2023-11-20 12:22:49.535] >>> TIM : FULL
[2023-11-20 12:22:49.535] Waking up MCU ...
[2023-11-20 12:22:49.551] [atcmd_transport_set_max_session:9488]Set max session(8), max CID(9)
[2023-11-20 12:22:49.551] [atcmd_network_get_sess_info:10549]Load sess_info(len:1240, cnt:10)
[2023-11-20 12:22:49.551] [atcmd_transport_ssl_recover_context_dpm:13406]Not found tls context
[2023-11-20 12:22:49.567]  BUF Address = 0x916f8
[2023-11-20 12:22:49.567]  AT Address = 0xd61d0
[2023-11-20 12:22:49.567] [host_init] slave_rx_int_evt addr = 0xd82e0
[2023-11-20 12:22:49.567] [host_init] slave_cmd_int_evt addr = 0xd83d0
[2023-11-20 12:22:49.567] [atcmd_network_recover_session:10627]Start
[2023-11-20 12:22:49.567] 
[2023-11-20 12:22:49.567] argv[0]:AT+MCUWUDONE
[2023-11-20 12:22:49.568] [atcmd_network_display_sess_info:9912]Session info
[2023-11-20 12:22:49.568] * CID                  : 1
[2023-11-20 12:22:49.568] * Type                 : 1
[2023-11-20 12:22:49.568] * Local port           : 38455
[2023-11-20 12:22:49.568] * Peer IP Address      : 192.168.101.33:11114
[2023-11-20 12:22:49.583] 
[2023-11-20 12:22:49.583] Usage: AT+MCUWUDONE=
[2023-11-20 12:22:49.583] 
[2023-11-20 12:22:49.583] argv[0]:AT+CLRDPMSLPEXT
[2023-11-20 12:22:49.583] [atcmd_transport_alloc_context:9520]size info(atcmd_sess_context:92, type:1, ctx_size:28)
[2023-11-20 12:22:49.583] 
[2023-11-20 12:22:49.583] Usage: AT+CLRDPMSLPEXT=
[2023-11-20 12:22:49.584] [atcmd_network_connect_tcpc:11053]Start
[2023-11-20 12:22:49.584] [atcmd_network_recover_session:10767]After CID: 1(1), 
[2023-11-20 12:22:49.759] 
[2023-11-20 12:22:49.759] argv[0]:AT+TRTRM
[2023-11-20 12:22:49.759] argv[1]:1
[2023-11-20 12:22:49.759] [atcmd_network_terminate_session:11506]cid(0x1)
[2023-11-20 12:22:49.759] [atcmd_transport_find_context:9739]Found cid(1) - type(1)
[2023-11-20 12:22:49.775] [atcmd_network_disconnect_tcpc:11135]Start(cid:1)
[2023-11-20 12:22:49.775] [atcmd_transport_find_context:9739]Found cid(1) - type(1)
[2023-11-20 12:22:49.871] [atcmd_transport_delete_context:9756]Before CID: 1(1), 
[2023-11-20 12:22:49.871] [atcmd_network_delete_sess_info:10603]Start
[2023-11-20 12:22:49.871] [atcmd_transport_delete_context:9799]After CID: 
[2023-11-20 12:22:49.871] 
[2023-11-20 12:22:49.871] Usage: AT+TRTRM=<cid>[,<remote_ip>,<remote_port>]
[2023-11-20 12:22:50.063] 
[2023-11-20 12:22:50.063] argv[0]:AT+SETDPMSLPEXT
[2023-11-20 12:22:50.063] 
[2023-11-20 12:22:50.063] Usage: AT+SETDPMSLPEXT=
[2023-11-20 12:22:50.879] >>> Start DPM Power-Down !!! 
[2023-11-20 12:22:50.879] PS TIME 13710�
[2023-11-20 12:22:50.911] Wakeup source is 0x81 
[2023-11-20 12:22:50.911] Initialize spi/sdio interrupt: 6
[2023-11-20 12:22:50.911] 
[2023-11-20 12:22:50.927] >>> Start DA16X Supplicant ...
[2023-11-20 12:22:50.943] >>> TIM STATUS: 0x00002000
[2023-11-20 12:22:50.943] >>> TIM : FULL
[2023-11-20 12:22:50.943] Waking up MCU ...
[2023-11-20 12:22:50.959] [atcmd_transport_set_max_session:9488]Set max session(8), max CID(9)
[2023-11-20 12:22:50.959] [atcmd_network_get_sess_info:10549]Load sess_info(len:1240, cnt:10)
[2023-11-20 12:22:50.959] [atcmd_transport_ssl_recover_context_dpm:13406]Not found tls context
[2023-11-20 12:22:50.959]  BUF Address = 0x916f8
[2023-11-20 12:22:50.959]  AT Address = 0xd61d0
[2023-11-20 12:22:50.960] [host_init] slave_rx_int_evt addr = 0xd82e0
[2023-11-20 12:22:50.975] [host_init] slave_cmd_int_evt addr = 0xd83d0
[2023-11-20 12:22:50.975] [atcmd_network_recover_session:10627]Start
[2023-11-20 12:22:50.975] 
[2023-11-20 12:22:50.975] argv[0]:AT+MCUWUDONE
[2023-11-20 12:22:50.975] [atcmd_network_recover_session:10767]After CID: 
[2023-11-20 12:22:50.976] Usage: AT+MCUWUDONE=
[2023-11-20 12:22:50.976] 
[2023-11-20 12:22:50.976] 
[2023-11-20 12:22:50.976] argv[0]:AT+CLRDPMSLPEXT
[2023-11-20 12:22:50.976] 
[2023-11-20 12:22:50.976] Usage: AT+CLRDPMSLPEXT=
[2023-11-20 12:22:51.167] 
[2023-11-20 12:22:51.167] argv[0]:AT+TRTC
[2023-11-20 12:22:51.167] argv[1]:192.168.101.33
[2023-11-20 12:22:51.183] argv[2]:11114
[2023-11-20 12:22:51.183] [atcmd_transport_create_context:9581]Before CID: 
[2023-11-20 12:22:51.183] [atcmd_transport_create_context:9587]sizeof(atcmd_sess_context):92
[2023-11-20 12:22:51.183] Created atcmd_sess_ctx_mutex
[2023-11-20 12:22:51.183] [atcmd_transport_alloc_context:9520]size info(atcmd_sess_context:92, type:1, ctx_size:28)
[2023-11-20 12:22:51.184] [atcmd_transport_create_context:9715]After CID: 1(1), 
[2023-11-20 12:22:51.184] [atcmd_network_connect_tcpc:11053]Start
[2023-11-20 12:22:51.184] 
[2023-11-20 12:22:51.184] Usage: AT+TRTC=<svr_ip>,<svr_port>(,<local_port>)
[2023-11-20 12:22:51.375] 
[2023-11-20 12:22:51.375] argv[0]:AT+SETDPMSLPEXT
[2023-11-20 12:22:51.375] 
[2023-11-20 12:22:51.375] Usage: AT+SETDPMSLPEXT=
[2023-11-20 12:22:51.391] >>> Start DPM Power-Down !!! 
[2023-11-20 12:22:51.391] PS TIME 4700�
[2023-11-20 12:22:51.407] Wakeup source is 0x81 
[2023-11-20 12:22:51.407] Initialize spi/sdio interrupt: 6
[2023-11-20 12:22:51.407] 
[2023-11-20 12:22:51.423] >>> Start DA16X Supplicant ...
[2023-11-20 12:22:51.439] >>> TIM STATUS: 0x00002000
[2023-11-20 12:22:51.439] >>> TIM : FULL
[2023-11-20 12:22:51.455] Waking up MCU ...
[2023-11-20 12:22:51.455] [atcmd_transport_set_max_session:9488]Set max session(8), max CID(9)
[2023-11-20 12:22:51.455] [atcmd_network_get_sess_info:10549]Load sess_info(len:1240, cnt:10)
[2023-11-20 12:22:51.471] [atcmd_transport_ssl_recover_context_dpm:13406]Not found tls context
[2023-11-20 12:22:51.471]  BUF Address = 0x916f8
[2023-11-20 12:22:51.471]  AT Address = 0xd61d0
[2023-11-20 12:22:51.472] [host_init] slave_rx_int_evt addr = 0xd82e0
[2023-11-20 12:22:51.472] [host_init] slave_cmd_int_evt addr = 0xd83d0
[2023-11-20 12:22:51.472] [atcmd_network_recover_session:10627]Start
[2023-11-20 12:22:51.472] [atcmd_network_display_sess_info:9912]Session info
[2023-11-20 12:22:51.472] * CID                  : 1
[2023-11-20 12:22:51.487] * Type                 : 1
[2023-11-20 12:22:51.487] * Local port           : 32401
[2023-11-20 12:22:51.487] * Peer IP Address      : 192.168.101.33:11114
[2023-11-20 12:22:51.487] 
[2023-11-20 12:22:51.487] argv[0]:AT+MCUWUDONE
[2023-11-20 12:22:51.487] [atcmd_transport_alloc_context:9520]size info(atcmd_sess_context:92, type:1, ctx_size:28)
[2023-11-20 12:22:51.488] [atcmd_network_connect_tcpc:11053]Start
[2023-11-20 12:22:51.488] 
[2023-11-20 12:22:51.488] Usage: AT+MCUWUDONE=
[2023-11-20 12:22:51.488] 
[2023-11-20 12:22:51.488] argv[0]:AT+CLRDPMSLPEXT
[2023-11-20 12:22:51.488] 
[2023-11-20 12:22:51.488] Usage: AT+CLRDPMSLPEXT=
[2023-11-20 12:22:51.488] [atcmd_network_recover_session:10767]After CID: 1(1), 
[2023-11-20 12:22:51.663] 
[2023-11-20 12:22:51.663] argv[0]:AT+TRTRM
[2023-11-20 12:22:51.663] argv[1]:1
[2023-11-20 12:22:51.679] [atcmd_network_terminate_session:11506]cid(0x1)
[2023-11-20 12:22:51.679] [atcmd_transport_find_context:9739]Found cid(1) - type(1)
[2023-11-20 12:22:51.679] [atcmd_network_disconnect_tcpc:11135]Start(cid:1)
[2023-11-20 12:22:51.679] [atcmd_transport_find_context:9739]Found cid(1) - type(1)
[2023-11-20 12:22:51.775] [atcmd_transport_delete_context:9756]Before CID: 1(1), 
[2023-11-20 12:22:51.775] [atcmd_network_delete_sess_info:10603]Start
[2023-11-20 12:22:51.775] [atcmd_transport_delete_context:9799]After CID: 
[2023-11-20 12:22:51.775] 
[2023-11-20 12:22:51.775] Usage: AT+TRTRM=<cid>[,<remote_ip>,<remote_port>]
[2023-11-20 12:22:51.967] 
[2023-11-20 12:22:51.967] argv[0]:AT+SETDPMSLPEXT
[2023-11-20 12:22:51.983] 
[2023-11-20 12:22:51.983] Usage: AT+SETDPMSLPEXT=
[2023-11-20 12:22:51.983] >>> Start DPM Power-Down !!! 
[2023-11-20 12:22:51.983] PS TIME 5600�
[2023-11-20 12:22:51.999] Wakeup source is 0x81 
[2023-11-20 12:22:51.999] Initialize spi/sdio interrupt: 6
[2023-11-20 12:22:51.999] 
[2023-11-20 12:22:52.015] >>> Start DA16X Supplicant ...
[2023-11-20 12:22:52.031] >>> TIM STATUS: 0x00002000
[2023-11-20 12:22:52.031] >>> TIM : FULL
[2023-11-20 12:22:52.047] Waking up MCU ...
[2023-11-20 12:22:52.047] [atcmd_transport_set_max_session:9488]Set max session(8), max CID(9)
[2023-11-20 12:22:52.047] [atcmd_network_get_sess_info:10549]Load sess_info(len:1240, cnt:10)
[2023-11-20 12:22:52.063] [atcmd_transport_ssl_recover_context_dpm:13406]Not found tls context
[2023-11-20 12:22:52.063]  BUF Address = 0x916f8
[2023-11-20 12:22:52.063]  AT Address = 0xd61d0
[2023-11-20 12:22:52.063] [host_init] slave_rx_int_evt addr = 0xd82e0
[2023-11-20 12:22:52.063] [host_init] slave_cmd_int_evt addr = 0xd83d0
[2023-11-20 12:22:52.064] [atcmd_network_recover_session:10627]Start
[2023-11-20 12:22:52.064] [atcmd_network_recover_session:10767]After CID: 
[2023-11-20 12:22:52.079] argv[0]:AT+MCUWUDONE
[2023-11-20 12:22:52.079] 
[2023-11-20 12:22:52.079] 
[2023-11-20 12:22:52.079] Usage: AT+MCUWUDONE=
[2023-11-20 12:22:52.079] 
[2023-11-20 12:22:52.079] argv[0]:AT+CLRDPMSLPEXT
[2023-11-20 12:22:52.079] 
[2023-11-20 12:22:52.079] Usage: AT+CLRDPMSLPEXT=
[2023-11-20 12:22:52.271] 
[2023-11-20 12:22:52.271] argv[0]:AT+TRTC
[2023-11-20 12:22:52.271] argv[1]:192.168.101.33
[2023-11-20 12:22:52.271] argv[2]:11114
[2023-11-20 12:22:52.271] [atcmd_transport_create_context:9581]Before CID: 
[2023-11-20 12:22:52.271] [atcmd_transport_create_context:9587]sizeof(atcmd_sess_context):92
[2023-11-20 12:22:52.271] Created atcmd_sess_ctx_mutex
[2023-11-20 12:22:52.287] [atcmd_transport_alloc_context:9520]size info(atcmd_sess_context:92, type:1, ctx_size:28)
[2023-11-20 12:22:52.287] [atcmd_transport_create_context:9715]After CID: 1(1), 
[2023-11-20 12:22:52.287] [atcmd_network_connect_tcpc:11053]Start
[2023-11-20 12:22:52.287] 
[2023-11-20 12:22:52.287] Usage: AT+TRTC=<svr_ip>,<svr_port>(,<local_port>)
[2023-11-20 12:22:52.479] 
[2023-11-20 12:22:52.479] argv[0]:AT+SETDPMSLPEXT
[2023-11-20 12:22:52.479] 
[2023-11-20 12:22:52.479] Usage: AT+SETDPMSLPEXT=
[2023-11-20 12:22:52.479] >>> Start DPM Power-Down !!! 
[2023-11-20 12:22:52.479] PS TIME 470019
[2023-11-20 12:22:52.511] Wakeup source is 0x81 
[2023-11-20 12:22:52.511] Initialize spi/sdio interrupt: 6
[2023-11-20 12:22:52.511] 
[2023-11-20 12:22:52.527] >>> Start DA16X Supplicant ...
[2023-11-20 12:22:52.543] >>> TIM STATUS: 0x00002000
[2023-11-20 12:22:52.543] >>> TIM : FULL
[2023-11-20 12:22:52.543] Waking up MCU ...
[2023-11-20 12:22:52.559] [atcmd_transport_set_max_session:9488]Set max session(8), max CID(9)
[2023-11-20 12:22:52.559] [atcmd_network_get_sess_info:10549]Load sess_info(len:1240, cnt:10)
[2023-11-20 12:22:52.559] [atcmd_transport_ssl_recover_context_dpm:13406]Not found tls context
[2023-11-20 12:22:52.559]  BUF Address = 0x916f8
[2023-11-20 12:22:52.560]  AT Address = 0xd61d0
[2023-11-20 12:22:52.560] [host_init] slave_rx_int_evt addr = 0xd82e0
[2023-11-20 12:22:52.575] [host_init] slave_cmd_int_evt addr = 0xd83d0
[2023-11-20 12:22:52.575] [atcmd_network_recover_session:10627]Start
[2023-11-20 12:22:52.575] [atcmd_network_display_sess_info:9912]Session info
[2023-11-20 12:22:52.575] * CID                  : 1
[2023-11-20 12:22:52.576] * Type                 : 1
[2023-11-20 12:22:52.576] * Local port           : 34909
[2023-11-20 12:22:52.576] * Peer IP Address      : 192.168.101.33:11114
[2023-11-20 12:22:52.576] 
[2023-11-20 12:22:52.576] argv[0]:AT+MCUWUDONE
[2023-11-20 12:22:52.576] [atcmd_transport_alloc_context:9520]size info(atcmd_sess_context:92, type:1, ctx_size:28)
[2023-11-20 12:22:52.591] 
[2023-11-20 12:22:52.591] Usage: AT+MCUWUDONE=
[2023-11-20 12:22:52.591] 
[2023-11-20 12:22:52.591] argv[0]:AT+CLRDPMSLPEXT
[2023-11-20 12:22:52.591] [atcmd_network_connect_tcpc:11053]Start
[2023-11-20 12:22:52.591] 
[2023-11-20 12:22:52.591] Usage: AT+CLRDPMSLPEXT=
[2023-11-20 12:22:52.591] [atcmd_network_recover_session:10767]After CID: 1(1), 
[2023-11-20 12:22:52.767] 
[2023-11-20 12:22:52.767] argv[0]:AT+TRTRM
[2023-11-20 12:22:52.767] argv[1]:1
[2023-11-20 12:22:52.767] [atcmd_network_terminate_session:11506]cid(0x1)
[2023-11-20 12:22:52.767] [atcmd_transport_find_context:9739]Found cid(1) - type(1)
[2023-11-20 12:22:52.767] [atcmd_network_disconnect_tcpc:11135]Start(cid:1)
[2023-11-20 12:22:52.767] [atcmd_transport_find_context:9739]Found cid(1) - type(1)
[2023-11-20 12:22:52.863] [atcmd_transport_delete_context:9756]Before CID: 1(1), 
[2023-11-20 12:22:52.863] [atcmd_network_delete_sess_info:10603]Start
[2023-11-20 12:22:52.879] [atcmd_transport_delete_context:9799]After CID: 
[2023-11-20 12:22:52.879] 
[2023-11-20 12:22:52.879] Usage: AT+TRTRM=<cid>[,<remote_ip>,<remote_port>]
[2023-11-20 12:22:53.071] 
[2023-11-20 12:22:53.071] argv[0]:AT+SETDPMSLPEXT
[2023-11-20 12:22:53.071] 
[2023-11-20 12:22:53.071] Usage: AT+SETDPMSLPEXT=
[2023-11-20 12:22:54.367] >>> Start DPM Power-Down !!! 
[2023-11-20 12:22:54.367] PS TIME 185005�

NOTE: the logs capture a program that calls additional configuration AT commands which were omitted from pseudocode for clarity.

Top Replies

0 OV_Renesas 17 days ago

Hi Jur,

Thank you for posting your question online.
Let me check on this and I will get back to you as soon as possible.
In the meantime, could you please check if the same issue occurs with our latest SDK v3.2.8.1?

Best Regards,
OV_Renesas
Cancel
Up +1 Down

Reply

Verify Answer

Cancel
0 OV_Renesas 15 days ago

Hi Jur,

Apologies for the delay.
FYI: We have created an internal ticket regarding this issue and we will get back to you as soon as possible.

Best Regards,
OV_Renesas
Cancel
Up +1 Down

Reply

Verify Answer

Cancel
0 OV_Renesas 14 days ago

Hi Jur,

We were not able to recreate the same issue with the UART interface.
We consider this to be a timing issue. If that is the case, those logs are not enough for us to find the source of the problem.
Would it be possible to capture with a logic analyzer the SPI lines (with and without the workaround) so we can have a look?

Best Regards,
OV_Renesas
Cancel
Up 0 Down

Reply

Verify Answer

Cancel
0 Jur 14 days ago in reply to OV_Renesas

Thanks for looking into it. I would agree that it is a timing issue (race condition) indeed!

I am quite busy at the moment but will provide the logic analyzer capture when my time allows it.

Best,
Jure
Cancel
Up 0 Down

Reply

Verify Answer

Cancel