Kwang-Hui
commented
3 years ago Hello @jsp0929 ,
The log indicates your device generate incomplete json reponse like below
{"protocolVersion":"1.2.0","firmwareVersion":"firmwareVersion_here","hashedSn":"5jNJ2RQlX76_1TsNUq36GK_8xPqJTyYWPdOLB4sUJkg=","wifiSupportFrequency":It should be complete json format including "iv" value like
{"protocolVersion":"1.2.0","firmwareVersion":"firmwareVersion_here","hashedSn":"5jNJ2RQlX76_1TsNUq36GK_8xPqJTyYWPdOLB4sUJkg=","wifiSupportFrequency":0,"iv":"xntx4EDX4gHHKtI75RvT-A=="}I'd like to suggest you to add debugging log like below https://github.com/SmartThingsCommunity/st-device-sdk-c/blob/4367e87df5538aa46bf2cd5505abb30df80fbe91/src/easysetup/http/tcp/iot_easysetup_d2d_tcp.c#L429
output_ptr = JSON_PRINT(root);
+ IOT_DEBUG("%s", output_ptr);
jsp0929
Hello, I'm trying to build a device with eap8266 using smarttings SDK. It always fails during onboarding process with error code 07-999.
I got information from st device SDK team that this unknown internal error(07-999) indicates that the WiFi connection between the esp8266 device and mobile phone is forcely closed. (I had sent them the log taken from my phone)
Here I open an issue on their request with the following serial terminal log. I hope someone help with this.
--------------------- Terminal log ------------------------------------------------------------------------------------- Toolchain path: /home/jspark/esp/xtensa-lx106-elf/bin/xtensa-lx106-elf-gcc Toolchain version: crosstool-ng-1.22.0-100-ge567ec7 Compiler version: 5.2.0 Python requirements from /home/jspark/st-device-sdk-c-ref/bsp/esp8266/requirements.txt are satisfied. MONITOR
ets Jan 8 2013,rst cause:2, boot mode:(3,7)
load 0x40100000, len 6508, room 16 tail 12 chksum 0xe4 ho 0 tail 12 room 4 load 0x3ffe8408, len 24, room 12 tail 12 chksum 0x6d ho 0 tail 12 room 4 load 0x3ffe8420, len 2832, room 12 tail 4 chksum 0x52 csum 0x52 D (279) nvs: nvs_flash_init_custom partition=nvs start=9 count=6 [0;32mI (300) system_api: Base MAC address is not set, read default base MAC address from EFUSE[0m D (304) nvs: nvs_open_from_partition backup_mac 0 D (306) nvs: nvs_get_str_or_blob backup_mac_data D (311) nvs: nvs_close 1 [0;32mI (313) system_api: Base MAC address is not set, read default base MAC address from EFUSE[0m D (321) nvs: nvs_open_from_partition backup_mac 0 D (326) nvs: nvs_get_str_or_blob backup_mac_data D (330) nvs: nvs_close 2 D (332) phy_init: loading PHY init data from application binary D (338) nvs: nvs_open_from_partition phy 0 D (342) nvs: nvs_get_str_or_blob cal_data D (346) nvs: nvs_get_str_or_blob dc_table D (351) nvs: nvs_close 3 phy_version: 1163.0, 665d56c, Jun 24 2020, 10:00:08, RTOS new [0;32mI (413) phy_init: phy ver: 1163_0[0m [0;32mI (416) reset_reason: RTC reset 2 wakeup 0 store 0, reason is 2[0m D (420) wdt: Enable task watch dog panic, panic time parameter is 11 D (430) wdt: task watch dog trigger time parameter is 15 D (438) [IoT]: iot_nv_init(197) > [0;36m>>>HIT<<<[0;m D (445) nvs: nvs_open_from_partition stdk 1 D (451) nvs: nvs_get_str_or_blob stnv [0;32mI (457) [IoT]: _iot_bsp_fs_encryption(98) > 'nvs' is encrypted[0m D (466) nvs: nvs_close 4 [0;32mI (479) [IoT]: _dump_device_info(706) > firmware_version: firmwareVersion_here[0m [0;32mI (483) [IoT]: iot_bsp_wifi_init(170) > [esp8266] iot_bsp_wifi_init[0m D (497) nvs: nvs_open_from_partition misc 1 D (499) nvs: nvs_open_from_partition nvs.net80211 1 D (506) nvs: nvs_get opmode 1 D (510) nvs: nvs_get_str_or_blob sta.ssid D (516) nvs: nvs_get_str_or_blob sta.mac D (521) nvs: nvs_get sta.authmode 1 D (526) nvs: nvs_get_str_or_blob sta.pswd D (532) nvs: nvs_get_str_or_blob sta.pmk D (538) nvs: nvs_get sta.chan 1 D (542) nvs: nvs_get auto.conn 1 D (547) nvs: nvs_get bssid.set 1 D (551) nvs: nvs_get_str_or_blob sta.bssid D (557) nvs: nvs_get sta.lis_intval 2 D (562) nvs: nvs_get sta.phym 1 D (567) nvs: nvs_get sta.phybw 1 D (571) nvs: nvs_get_str_or_blob sta.apsw D (577) nvs: nvs_get_str_or_blob sta.apinfo D (584) nvs: nvs_get sta.scan_method 1 D (588) nvs: nvs_get sta.sort_method 1 D (594) nvs: nvs_get sta.minrssi 1 D (598) nvs: nvs_get sta.minauth 1 D (603) nvs: nvs_get_str_or_blob ap.ssid D (609) nvs: nvs_get_str_or_blob ap.mac D (614) nvs: nvs_get_str_or_blob ap.passwd D (620) nvs: nvs_get_str_or_blob ap.pmk D (626) nvs: nvs_get ap.chan 1 D (630) nvs: nvs_get ap.authmode 1 D (635) nvs: nvs_get ap.hidden 1 D (639) nvs: nvs_get ap.max.conn 1 D (644) nvs: nvs_get bcn.interval 2 D (649) nvs: nvs_get ap.phym 1 D (653) nvs: nvs_get ap.phybw 1 D (658) nvs: nvs_get ap.sndchan 1 D (662) nvs: nvs_get lorate 1 [0;32mI (668) [IoT]: iot_bsp_wifi_init(208) > [esp8266] iot_bsp_wifi_init done[0m D (678) [IoT]: iot_dump_log(346) > LOG : 40000701 00000000 00000000 00000000 [0;32mI (689) [IoT]: st_conn_init(1369) > stdk_version : 1.6.2[0m D (698) [IoT]: iot_dump_log(346) > LOG : 30000000 00000000 0000055c 00010602 [0;32mI (708) gpio: GPIO[13]| InputEn: 0| OutputEn: 1| OpenDrain: 0| Pullup: 0| Pulldown: 0| Intr:0 [0m [0;32mI (722) gpio: GPIO[16]| InputEn: 0| OutputEn: 1| OpenDrain: 0| Pullup: 0| Pulldown: 1| Intr:0 [0m [0;32mI (736) gpio: GPIO[14]| InputEn: 0| OutputEn: 1| OpenDrain: 0| Pullup: 0| Pulldown: 0| Intr:0 [0m [0;32mI (751) gpio: GPIO[12]| InputEn: 0| OutputEn: 1| OpenDrain: 0| Pullup: 0| Pulldown: 0| Intr:0 [0m [0;32mI (765) gpio: GPIO[2]| InputEn: 0| OutputEn: 1| OpenDrain: 0| Pullup: 0| Pulldown: 0| Intr:0 [0m [0;32mI (779) gpio: GPIO[5]| InputEn: 1| OutputEn: 0| OpenDrain: 0| Pullup: 1| Pulldown: 0| Intr:3 [0m [0;32mI (794) gpio: GPIO[12]| InputEn: 0| OutputEn: 1| OpenDrain: 0| Pullup: 0| Pulldown: 0| Intr:0 [0m [0;32mI (808) gpio: GPIO[13]| InputEn: 0| OutputEn: 1| OpenDrain: 0| Pullup: 0| Pulldown: 0| Intr:0 [0m [0;32mI (822) gpio: GPIO[14]| InputEn: 0| OutputEn: 1| OpenDrain: 0| Pullup: 0| Pulldown: 0| Intr:0 [0m [0;32mI (836) gpio: GPIO[15]| InputEn: 0| OutputEn: 1| OpenDrain: 0| Pullup: 0| Pulldown: 0| Intr:0 [0m [0;32mI (850) pwm: --- PWM v3.2 [0m [0;32mI (857) [IoT]: st_conn_start(1839) > st_conn_start start (no-pin)[0m D (867) [IoT]: iot_dump_log(346) > LOG : 30000000 00000000 00000730 00000000 D (877) [IoT]: iot_security_be_init(1904) > security backend is 'software' D (888) [IoT]: _iot_main_task(1118) > cmd: 10
[0;32mI (894) [IoT]: _do_iot_main_command(499) > curr_main_cmd:10, curr_main_state:-1/-1[0m D (906) [IoT]: iot_dump_log(346) > LOG : 30000002 00000000 00000000 00000000 [0;32mI (917) [IoT]: _do_update_timeout(200) > Current timeout : 100000 for -1/0[0m [0;32mI (928) [IoT]: _do_status_report(304) > Call usr status_cb with 1/0[0m status: 1, stat: 0 D (942) [IoT]: _iot_main_task(1118) > cmd: 2
[0;32mI (948) [IoT]: _do_iot_main_command(499) > curr_main_cmd:2, curr_main_state:-1/0[0m D (960) [IoT]: iot_nv_get_prov_data(261) > [0;36m>>>HIT<<<[0;m D (969) [IoT]: iot_nv_get_wifi_prov_data(322) > [0;36m>>>HIT<<<[0;m D (979) [IoT]: _iot_nv_io_storage(103) > id = 1, mode = 1 D (986) [IoT]: iot_security_be_init(1904) > security backend is 'software' D (996) [IoT]: iot_security_storage_read(114) > id:1 D (1004) [IoT]: _iot_security_be_bsp_fs_load(187) > id = 1 D (1012) [IoT]: _iot_security_be_bsp_fs_load_from_nv(100) > id = 1 D (1021) [IoT]: iot_bsp_nv_get_data_path(25) > [0;36m>>>HIT<<<[0;m D (1030) [IoT]: _iot_security_be_bsp_fs_storage_id2filename(85) > storage file = 'WifiProvStatus' D (1043) nvs: nvs_open_from_partition stdk 0 D (1049) nvs: nvs_get_str_or_blob WifiProvStatus D (1056) [IoT]: iot_bsp_fs_read(297) > not found 'WifiProvStatus' D (1065) nvs: nvs_close 7 D (1069) [IoT]: iot_security_storage_read(141) > id:1 not found D (1077) [IoT]: _iot_nv_io_storage(123) > nv '1' does not exist D (1086) [IoT]: iot_nv_get_wifi_prov_data(343) > Wifi Prov Status : read failed D (1097) [IoT]: iot_dump_log(346) > LOG : 40000601 00000000 00000001 00000158 D (1107) [IoT]: iot_nv_get_prov_data(267) > get wifi prov fail D (1116) [IoT]: _do_iot_main_command(626) > There are no prov data in NV
D (1126) [IoT]: iot_nv_erase(1006) > [0;36m>>>HIT<<<[0;m D (1134) [IoT]: _iot_nv_io_storage(103) > id = 10, mode = 3 D (1142) [IoT]: iot_security_be_init(1904) > security backend is 'software' D (1152) [IoT]: iot_security_storage_remove(191) > id:10 D (1160) [IoT]: _iot_security_be_bsp_fs_remove(309) > id = 10 D (1169) [IoT]: iot_bsp_nv_get_data_path(25) > [0;36m>>>HIT<<<[0;m D (1178) [IoT]: _iot_security_be_bsp_fs_storage_id2filename(85) > storage file = 'DeviceID' D (1190) nvs: nvs_open_from_partition stdk 1 D (1196) nvs: nvs_erase_key DeviceID
D (1203) [IoT]: iot_bsp_fs_remove(417) > nvs erase failed [Namespace/Key Not Found] D (1213) nvs: nvs_close 8 D (1217) [IoT]: iot_dump_log(346) > LOG : 10000378 00000000 00000126 00000000 D (1227) [IoT]: iot_security_storage_remove(211) > id:10 not found D (1236) [IoT]: _iot_nv_io_storage(165) > nv '10' does not exist D (1245) [IoT]: iot_nv_erase(1014) > file does not exist D (1253) [IoT]: iot_dump_log(346) > LOG : 40000604 00000000 0000000a 000003f7 [0;32mI (1264) [IoT]: _do_cmd_tout_check(1041) > New state updated for 0[0m D (1274) [IoT]: _iot_main_task(1118) > cmd: 10
[0;32mI (1281) [IoT]: _do_iot_main_command(499) > curr_main_cmd:10, curr_main_state:0/0[0m [0;32mI (1294) [IoT]: iot_bsp_wifi_set_mode(226) > iot_bsp_wifi_set_mode = 1[0m D (1305) [IoT]: iot_dump_log(346) > LOG : 40000703 00000001 00000001 00000000 D (1316) event: SYSTEM_EVENT_STA_START [0;32mI (1321) [IoT]: iot_bsp_wifi_set_mode(257) > WiFi Station Started[0m D (3754) event: SYSTEM_EVENT_SCAN_DONE, status:0, number:13 [0;32mI (3757) [IoT]: event_handler(159) > event_handler = 1[0m D (3846) [IoT]: iot_bsp_wifi_get_scan_result(430) > esp8266 ssid=iptime_jspark, mac=92:9F:33:47:71:F0, rssi=-62, freq=2452, authmode=3 chan=9 D (3854) [IoT]: iot_bsp_wifi_get_scan_result(430) > esp8266 ssid=myhome, mac=88:36:6C:4B:EB:3C, rssi=-73, freq=2452, authmode=3 chan=9 D (3872) [IoT]: iot_bsp_wifi_get_scan_result(430) > esp8266 ssid=[washer]_E30AJT2103403K, mac=28:6D:97:D4:9F:A4, rssi=-76, freq=2412, authmode=3 chan=1 D (3892) [IoT]: iot_bsp_wifi_get_scan_result(430) > esp8266 ssid=SK_WiFiGIGA6458, mac=88:3C:1C:B8:64:5B, rssi=-77, freq=2447, authmode=4 chan=8 D (3912) [IoT]: iot_bsp_wifi_get_scan_result(430) > esp8266 ssid=jspark_home, mac=2C:BA:BA:56:09:2A, rssi=-78, freq=2432, authmode=3 chan=5 D (3930) [IoT]: iot_bsp_wifi_get_scan_result(430) > esp8266 ssid=ryu_house, mac=70:5D:CC:92:28:CF, rssi=-81, freq=2437, authmode=3 chan=6 D (3949) [IoT]: iot_bsp_wifi_get_scan_result(430) > esp8266 ssid=KT_GiGA_2G_Wave2_A2C4, mac=88:3C:1C:D5:A2:C8, rssi=-85, freq=2452, authmode=4 chan=9 D (3969) [IoT]: iot_bsp_wifi_get_scan_result(430) > esp8266 ssid=NETGEAR74, mac=9C:C9:EB:0E:7E:3B, rssi=-87, freq=2432, authmode=3 chan=5 D (3988) [IoT]: iot_bsp_wifi_get_scan_result(430) > esp8266 ssid=[floor a/c]_E30AJT0003080Z, mac=28:6D:97:98:DB:16, rssi=-88, freq=2412, authmode=3 chan=1 D (4009) [IoT]: iot_bsp_wifi_get_scan_result(430) > esp8266 ssid=SK_WiFiGIGA5BC8_2.4G, mac=42:23:AA:E3:5B:CA, rssi=-89, freq=2462, authmode=4 chan=11 D (4029) [IoT]: iot_bsp_wifi_get_scan_result(430) > esp8266 ssid=KimFamily, mac=88:36:6C:CF:E4:E4, rssi=-91, freq=2462, authmode=3 chan=11 D (4047) [IoT]: iot_bsp_wifi_get_scan_result(430) > esp8266 ssid=SK_WiFiGIGA5BC8, mac=00:23:AA:E3:5B:CA, rssi=-92, freq=2462, authmode=4 chan=11 D (4067) [IoT]: iot_bsp_wifi_get_scan_result(430) > esp8266 ssid=iptime EasyMesh_2G, mac=70:5D:CC:80:1A:64, rssi=-93, freq=2427, authmode=3 chan=4 D (4086) [IoT]: iot_nv_get_serial_number(899) > [0;36m>>>HIT<<<[0;m D (4095) [IoT]: _iot_nv_io_storage(103) > id = 17, mode = 1 D (4103) [IoT]: iot_security_be_init(1904) > security backend is 'software' D (4113) [IoT]: iot_security_storage_read(114) > id:17 D (4121) [IoT]: _iot_security_be_bsp_fs_load(187) > id = 17 D (4129) [IoT]: iot_nv_get_data_from_device_info(1072) > nv id = 17 D (4141) [IoT]: iot_security_storage_read(146) > id:17 read 16@0x40108a78 D (4149) [IoT]: iot_security_base64_encode_urlsafe(178) > src: 32@0x3fff42b4, dst: 45@0x3fff42d4 D (4162) [IoT]: iot_security_base64_encode_urlsafe(192) > done: 44@0x3fff42d4 [0;32mI (4172) [IoT]: iot_easysetup_create_ssid(91) > >> LED_Light[LOuQ] <<[0m [0;32mI (4184) [IoT]: _do_update_timeout(200) > Current timeout : 300000 for 0/1[0m D (4195) [IoT]: _iot_main_task(1118) > cmd: 1
[0;32mI (4201) [IoT]: _do_iot_main_command(499) > curr_main_cmd:1, curr_main_state:0/1[0m [0;32mI (4214) [IoT]: iot_bsp_wifi_set_mode(226) > iot_bsp_wifi_set_mode = 3[0m D (4225) [IoT]: iot_dump_log(346) > LOG : 40000703 00000003 00000003 00000000 D (4237) event: SYSTEM_EVENT_AP_START D (4241) tcpip_adapter: dhcp server start:(ip: 192.168.4.1, mask: 255.255.255.0, gw: 192.168.4.1) [0;32mI (4254) [IoT]: event_handler(135) > SYSTEM_EVENT_AP_START[0m D (6127) event: SYSTEM_EVENT_AP_STOP D (6129) udp_sync: UDP sync close port 67 [0;32mI (6132) [IoT]: event_handler(140) > SYSTEM_EVENT_AP_STOP[0m D (6134) event: SYSTEM_EVENT_AP_START D (6138) tcpip_adapter: dhcp server start:(ip: 192.168.4.1, mask: 255.255.255.0, gw: 192.168.4.1) [0;32mI (6151) [IoT]: event_handler(135) > SYSTEM_EVENT_AP_START[0m D (6161) [IoT]: iot_bsp_wifi_set_mode(368) > wifi_init_softap finished.SSID:LED_Light_E4fPpd00065jNJLOuQ password:1111122222 [0;32mI (6177) [IoT]: iot_bsp_wifi_set_mode(374) > AP Mode Started[0m D (6187) [IoT]: iot_easysetup_init(466) > [0;36mENTER >>>>[0;m [0;32mI (6196) [IoT]: iot_easysetup_init(467) > IOT_STATE_PROV_ES_START[0m D (6207) [IoT]: iot_dump_log(346) > LOG : 3000054a 00000005 000001d4 00000000 [0;32mI (6218) [IoT]: es_http_init(159) > http tcp init!![0m D (6226) [IoT]: iot_dump_log(346) > LOG : 30000591 00000005 000000a0 00000000 D (6239) [IoT]: iot_dump_log(346) > LOG : 30000591 00000005 000000a2 00000001 [0;32mI (6248) [IoT]: iot_easysetup_init(497) > IOT_STATE_PROV_ES_INIT_DONE[0m D (6259) [IoT]: iot_dump_log(346) > LOG : 3000054a 00000005 000001f2 00000001 [0;32mI (6269) [IoT]: _do_cmd_tout_check(1041) > New state updated for 1[0m [0;32mI (6280) [IoT]: _do_status_report(304) > Call usr status_cb with 2/1[0m status: 2, stat: 1 D (32344) event: SYSTEM_EVENT_AP_STACONNECTED, mac:5e:d0:bc:a7:3d:d4, aid:1 [0;32mI (32349) [IoT]: event_handler(147) > station:5e:d0:bc:a7:3d:d4 join, AID=1[0m D (33040) udp_sync: UDP sync cache udp 0x401083e0 D (33044) udp_sync: UDP sync clear udp 0x401083e0 D (33072) udp_sync: UDP sync cache udp 0x401083e0 D (33075) udp_sync: UDP sync clear udp 0x401083e0 [0;32mI (33078) tcpip_adapter: softAP assign IP to station,IP is: 192.168.4.2[0m D (33082) event: SYSTEM_EVENT_AP_STAIPASSIGNED [0;32mI (33089) [IoT]: event_handler(159) > event_handler = 17[0m [0;32mI (36283) [IoT]: _iot_easysetup_gen_get_payload(151) > waiting.. response for [0][0m D (36287) [IoT]: iot_dump_log(346) > LOG : 30000547 00000024 00000098 00000000 D (36293) [IoT]: _iot_main_task(1118) > cmd: 10
[0;32mI (36300) [IoT]: _do_iot_main_command(499) > curr_main_cmd:10, curr_main_state:1/1[0m [0;32mI (36313) [IoT]: _do_state_updating(1635) > Notification only with IOT_STATE_PROV_CONN_MOBILE[0m [0;32mI (36329) [IoT]: _do_status_report(304) > Call usr status_cb with 2/4[0m status: 2, stat: 4 D (36341) [IoT]: _iot_main_task(1143) > request step: 0
D (36349) [IoT]: iot_security_base64_encode_urlsafe(178) > src: 16@0x40108b98, dst: 25@0x40108bc8 D (36362) [IoT]: iot_security_base64_encode_urlsafe(192) > done: 24@0x40108bc8 D (36374) [IoT]: _iot_easysetup_gen_get_payload(156) > easysetup response for [0] D (36384) [IoT]: _iot_easysetup_gen_get_payload(170) > payload: {"protocolVersion":"1.2.0","firmwareVersion":"firmwareVersion_here","hashedSn":"5jNJ2RQlX76_1TsNUq36GK_8xPqJTyYWPdOLB4sUJkg=","wifiSupportFrequency": [0;32mI (36415) [IoT]: http_msg_handler(394) > get cmd[0] ok[0m D (36422) [IoT]: iot_dump_log(346) > LOG : 30000548 00000024 0000018b 00000000 D (36433) [IoT]: http_msg_handler(450) > HTTP/1.1 200 OK
Server: SmartThings SDK Setup
Connection: keep-alive
Content-Type: application/json
Content-Length: 149
{"protocolVersion":"1.2.0","firmwareVersion":"firmwareVersion_here","hashedSn":"5jNJ2RQlX76_1TsNUq36GK_8xPqJTyYWPdOLB4sUJkg=","wifiSupportFrequency": D (36563) event: SYSTEM_EVENT_AP_STADISCONNECTED, mac:5e:d0:bc:a7:3d:d4, aid:1 [0;32mI (36568) [IoT]: event_handler(153) > station:5e:d0:bc:a7:3d:d4 leave, AID=1[0m
I quit the terminal log after onboarding error pops up.