Skip to content
New issue

Have a question about this project? # for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “#”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? # to your account

Troubleshooting continuation from #14032 #14043

Closed
8 tasks
Hans-Sh opened this issue Dec 14, 2021 · 16 comments
Closed
8 tasks

Troubleshooting continuation from #14032 #14043

Hans-Sh opened this issue Dec 14, 2021 · 16 comments
Labels
troubleshooting Type - Troubleshooting

Comments

@Hans-Sh
Copy link

Hans-Sh commented Dec 14, 2021

PROBLEM DESCRIPTION

Approx. 14 hours after reflashing Sonoff Basic R2 with 10.1.0.1 (fix for #14032), which solved Wifi issues and connected fast and easy to Asus network in N mode, I was unable to connect to this device via Web. Serial log showed it had problems to connect to ASUS (2,4 and 5 Ghz SSID's separated) in N mode. It could connect after Wifi 3 command but it showed Execption 9 and 0. See the serial log.

REQUESTED INFORMATION

Make sure your have performed every step and checked the applicable boxes before submitting your issue. Thank you!

  • [v ] Read the Contributing Guide and Policy and the Code of Conduct
  • [v ] Searched the problem in issues
  • [v ] Searched the problem in discussions
  • v[ ] Searched the problem in the docs
  • Searched the problem in the chat
  • [v ] Device used (e.g., Sonoff Basic): Basic R2_
  • Tasmota binary firmware version number used: 10.1.0.1
    • Pre-compiled
    • Self-compiled
  • Flashing tools used: Tasmotizer 1.2 for Windows
  • Provide the output of command: Backlog Template; Module; GPIO 255:
  Configuration output here:
14:36:28.729 CMD: Backlog Template; Module; GPIO 255
14:36:28.731 SRC: WebConsole from 192.168.2.34
14:36:28.734 CMD: Grp 0, Cmnd 'BACKLOG', Idx 1, Len 26, Data 'Template; Module; GPIO 255'
14:36:28.791 SRC: Backlog
14:36:28.793 CMD: Grp 0, Cmnd 'TEMPLATE', Idx 1, Len 0, Data ''
14:36:28.800 MQT: HLso7/RESULT = {"NAME":"Generic","GPIO":[1,1,1,1,1,1,1,1,1,1,1,1,1,1],"FLAG":0,"BASE":18}
14:36:29.007 SRC: Backlog
14:36:29.010 CMD: Grp 0, Cmnd 'MODULE', Idx 1, Len 0, Data ''
14:36:29.015 MQT: HLso7/RESULT = {"Module":{"1":"Sonoff Basic"}}
14:36:29.162 DOM: idx 157, nvalue 0
14:36:29.258 SRC: Backlog
14:36:29.261 CMD: Grp 0, Cmnd 'GPIO', Idx 1, Len 3, Data '255'

  • If using rules, provide the output of this command: Backlog Rule1; Rule2; Rule3:
  Rules output here:
none
  • Provide the output of this command: Status 0:
  STATUS 0 output here:
For test purposes now MQTT active, The Web access and Excepptions happened without MQTT was active.

00:00:00.001 HDW: ESP8266EX
00:00:00.050 CFG: Loaded from flash at FA, Count 58
00:00:00.055 QPC: Count 1
00:00:00.064 Project tasmota - Tasmota Version 10.1.0.1(tasmota)-2_7_4_9(2021-12-12T20:09:35)
00:00:04.503 WIF: Connecting to AP2 HLinc2 Channel 6 BSSId 18:31:BF:36:A0:98 in mode 11n as HLso7...
00:00:06.525 QPC: Reset
00:00:13.800 WIF: Connect failed with AP timeout
00:00:26.768 WIF: Connect failed with AP timeout
00:00:32.124 WIF: Connecting to AP2 HLinc2 Channel 6 BSSId 18:31:BF:36:A0:98 in mode 11n as HLso7...
00:00:41.760 WIF: Connect failed with AP timeout
00:00:54.781 WIF: Connect failed with AP timeout
00:01:00.131 WIF: Connecting to AP2 HLinc2 Channel 6 BSSId 18:31:BF:36:A0:98 in mode 11n as HLso7...
00:01:09.770 WIF: Connect failed with AP timeout
00:01:22.789 WIF: Connect failed with AP timeout
00:01:28.147 WIF: Connecting to AP2 HLinc2 Channel 6 BSSId 18:31:BF:36:A0:98 in mode 11n as HLso7...
00:01:37.768 WIF: Connect failed with AP timeout
00:01:50.789 WIF: Connect failed with AP timeout
00:01:56.146 WIF: Connecting to AP2 HLinc2 Channel 6 BSSId 18:31:BF:36:A0:98 in mode 11n as HLso7...
00:02:05.770 WIF: Connect failed with AP timeout
00:02:18.800 WIF: Connect failed with AP timeout
00:02:24.104 WIF: Connecting to AP2 HLinc2 Channel 6 BSSId 18:31:BF:36:A0:98 in mode 11n as HLso7...
00:02:33.762 WIF: Connect failed with AP timeout
00:02:46.782 WIF: Connect failed with AP timeout
00:02:52.141 WIF: Connecting to AP2 HLinc2 Channel 6 BSSId 18:31:BF:36:A0:98 in mode 11n as HLso7...
reset 3
00:02:56.333 CMD: reset 3
00:02:56.340 RSL: RESULT = {"Reset":"Erase, Reset and Restarting"}
00:02:59.633 APP: Restarting

 ets Jan  8 2013,rst cause:1, boot mode:(3,6)

load 0x4010f000, len 3584, room 16 
tail 0
chksum 0xb0
csum 0xb0
v3969889e
~ld

00:00:00.002 HDW: ESP8266EX
00:00:00.051 CFG: Loaded from flash at F9, Count 59
00:00:00.056 QPC: Count 1
00:00:00.065 Project tasmota - Tasmota Version 10.1.0.1(tasmota)-2_7_4_9(2021-12-12T20:09:35)
00:00:04.400 WIF: Connecting to AP2 HLinc2 Channel 6 BSSId 18:31:BF:36:A0:98 in mode 11n as HLso7...
00:00:06.249 QPC: Reset
00:00:13.787 WIF: Connect failed with AP timeout
00:00:26.802 WIF: Connect failed with AP timeout
00:00:32.097 WIF: Connecting to AP2 HLinc2 Channel 6 BSSId 18:31:BF:36:A0:98 in mode 11n as HLso7...
00:00:41.759 WIF: Connect failed with AP timeout
00:00:54.784 WIF: Connect failed with AP timeout
00:01:00.137 WIF: Connecting to AP2 HLinc2 Channel 6 BSSId 18:31:BF:36:A0:98 in mode 11n as HLso7...
00:01:09.759 WIF: Connect failed with AP timeout
seriallog 4
00:01:17.318 CMD: seriallog 4
00:01:17.326 RSL: RESULT = {"SerialLog":{"4":{"Active":"4"}}}
00:01:17.577 CFG: Saved to flash at F7, Count 61, Bytes 4096
00:01:17.767 WIF: Checking connection...
00:01:17.769 WIF: Attempting connection...
00:01:18.768 WIF: Checking connection...
00:01:18.771 WIF: Attempting connection...
00:01:19.771 WIF: Checking connection...
00:01:19.773 WIF: Attempting connection...
00:01:20.774 WIF: Checking connection...
00:01:20.776 WIF: Attempting connection...
00:01:21.777 WIF: Checking connection...
00:01:21.779 WIF: Attempting connection...
00:01:22.777 WIF: Checking connection...
00:01:22.780 WIF: Connect failed with AP timeout
00:01:23.778 WIF: Checking connection...
00:01:23.781 WIF: Attempting connection...
00:01:24.785 WIF: Network (re)scan started...
00:01:24.787 WIF: Checking connection...
00:01:24.789 WIF: Attempting connection...
00:01:25.782 WIF: Checking connection...
00:01:25.785 WIF: Attempting connection...
00:01:26.784 WIF: Checking connection...
00:01:26.786 WIF: Attempting connection...
00:01:27.784 WIF: Network 0, AP2, SSId HLinc2, Channel 6, BSSId 18:31:BF:36:A0:98, RSSI -32, Encryption 1
00:01:27.787 WIF: Network 1, AP2, SSId HLinc2, Channel 6, BSSId E0:3F:49:EE:88:21, RSSI -86, Encryption 1
00:01:27.795 WIF: Network 2, AP1, SSId HLinc, Channel 10, BSSId 28:EE:52:C1:A4:52, RSSI -69, Encryption 1
00:01:27.804 WIF: Network 3, AP1, SSId HLinc, Channel 10, BSSId 28:EE:52:C1:D1:D6, RSSI -77, Encryption 1
00:01:28.150 WIF: Connecting to AP2 HLinc2 Channel 6 BSSId 18:31:BF:36:A0:98 in mode 11n as HLso7...
00:01:28.152 WIF: Checking connection...
00:01:28.154 WIF: Attempting connection...
00:01:29.753 WIF: Checking connection...
00:01:29.755 WIF: Attempting connection...
00:01:30.752 WIF: Checking connection...
00:01:30.754 WIF: Attempting connection...
00:01:31.754 WIF: Checking connection...
00:01:31.756 WIF: Attempting connection...
00:01:32.758 WIF: Checking connection...
00:01:32.760 WIF: Attempting connection...
00:01:33.757 WIF: Checking connection...
00:01:33.759 WIF: Attempting connection...
00:01:34.759 WIF: Checking connection...
00:01:34.761 WIF: Attempting connection...
00:01:35.762 WIF: Checking connection...
00:01:35.764 WIF: Attempting connection...
00:01:36.764 WIF: Checking connection...
00:01:36.766 WIF: Attempting connection...
00:01:37.766 WIF: Checking connection...
00:01:37.767 WIF: Connect failed with AP timeout
00:01:38.769 WIF: Checking connection...
00:01:38.771 WIF: Attempting connection...
00:01:39.772 WIF: Checking connection...
00:01:39.774 WIF: Attempting connection...
00:01:40.777 WIF: Checking connection...
00:01:40.779 WIF: Attempting connection...
00:01:41.778 WIF: Checking connection...
00:01:41.781 WIF: Attempting connection...
wifi 3
00:01:42.657 CMD: wifi 3
00:01:42.658 SRC: Serial
00:01:42.661 CMD: Grp 0, Cmnd 'WIFI', Idx 1, Len 1, Data '3'
00:01:42.775 RSL: RESULT = {"Wifi":"ON","Mode":"11g"}
00:01:42.846 WIF: Checking connection...
00:01:42.849 WIF: Attempting connection...

 ets Jan  8 2013,rst cause:4, boot mode:(3,6)

wdt reset
load 0x4010f000, len 3584, room 16 
tail 0
chksum 0xb0
csum 0xb0
v3969889e
~ld

00:00:00.001 HDW: ESP8266EX
00:00:00.050 CFG: Loaded from flash at F7, Count 61
00:00:00.056 QPC: Count 1
00:00:00.061 ROT: Mode 1
00:00:00.062 SRC: Restart
00:00:00.064 Project tasmota - Tasmota Version 10.1.0.1(tasmota)-2_7_4_9(2021-12-12T20:09:35)
00:00:00.216 WIF: Checking connection...
00:00:00.217 WIF: Attempting connection...
00:00:01.160 WIF: Network (re)scan started...
00:00:01.161 WIF: Checking connection...
00:00:01.161 WIF: Attempting connection...
00:00:02.160 WIF: Checking connection...
00:00:02.161 WIF: Attempting connection...
00:00:03.163 WIF: Checking connection...
00:00:03.164 WIF: Attempting connection...
00:00:04.167 WIF: Network 0, AP-, SSId Pals netwerk, Channel 1, BSSId B4:FB:E4:74:5E:56, RSSI -86, Encryption 1
00:00:04.169 WIF: Network 1, AP-, SSId Pals gast, Channel 1, BSSId B6:FB:E4:74:5E:56, RSSI -86, Encryption 1
00:00:04.177 WIF: Network 2, AP2, SSId HLinc2, Channel 6, BSSId 18:31:BF:36:A0:98, RSSI -34, Encryption 1
00:00:04.186 WIF: Network 3, AP1, SSId HLinc, Channel 10, BSSId 28:EE:52:C1:A4:52, RSSI -67, Encryption 1
00:00:04.195 WIF: Network 4, AP1, SSId HLinc, Channel 10, BSSId 28:EE:52:C1:D1:D6, RSSI -74, Encryption 1
00:00:04.542 WIF: Connecting to AP2 HLinc2 Channel 6 BSSId 18:31:BF:36:A0:98 in mode 11g as HLso7...
00:00:04.543 WIF: Checking connection...
00:00:04.545 WIF: Attempting connection...
00:00:05.802 WIF: Checking connection...
00:00:05.803 WIF: Attempting connection...
00:00:06.474 QPC: Reset
00:00:06.768 WIF: Checking connection...
00:00:06.770 WIF: Attempting connection...
00:00:07.769 WIF: Checking connection...
00:00:07.770 WIF: Attempting connection...
00:00:08.422 APP: Boot Count 24
00:00:08.602 CFG: Saved to flash at F6, Count 62, Bytes 4096
00:00:08.799 WIF: Checking connection...
00:00:08.801 WIF: Connected
00:00:09.052 HTP: Web server active on HLso7 with IP address 192.168.2.90
00:00:09.399 WIF: Sending Gratuitous ARP
00:00:09.401 NTP: Synch time...
00:00:09.428 RTC: UTC 2021-12-14T12:27:11, DST 2021-03-28T02:00:00, STD 2021-10-31T03:00:00
13:27:11.000 NTP: Synched
13:27:11.180 CFG: Saved to flash at F5, Count 63, Bytes 4096
13:27:12.015 RSL: INFO1 = {"Info1":{"Module":"Sonoff Basic","Version":"10.1.0.1(tasmota)","FallbackTopic":"cmnd/DVES_AF718E_fb/","GroupTopic":"cmnd/tasmotas/"}}
13:27:12.021 RSL: INFO2 = {"Info2":{"WebServerMode":"Admin","Hostname":"HLso7","IPAddress":"192.168.2.90"}}
13:27:12.030 RSL: INFO3 = {"Info3":{"RestartReason":"Hardware Watchdog"}}
13:27:12.036 RSL: RESULT = {"POWER":"ON"}
13:27:12.040 RSL: POWER = ON
13:27:16.420 RSL: STATE = {"Time":"2021-12-14T13:27:16","Uptime":"0T00:00:16","UptimeSec":16,"Heap":29,"SleepMode":"Dynamic","Sleep":50,"LoadAvg":19,"MqttCount":0,"POWER":"ON","Wifi":{"AP":2,"SSId":"HLinc2","BSSId":"18:31:BF:36:A0:98","Channel":6,"Mode":"11g","RSSI":100,"Signal":-40,"LinkCount":1,"Downtime":"0T00:00:10"}}
13:27:30.786 WIF: Checking connection...
13:27:50.769 WIF: Checking connection...
13:28:02.433 WIF: Sending Gratuitous ARP
13:28:10.798 WIF: Checking connection...
13:28:30.776 WIF: Checking connection...
13:28:50.756 WIF: Checking connection...
13:29:02.423 WIF: Sending Gratuitous ARP
13:29:10.790 WIF: Checking connection...
13:29:30.771 WIF: Checking connection...
13:29:50.754 WIF: Checking connection...
13:30:02.428 WIF: Sending Gratuitous ARP
13:30:10.793 WIF: Checking connection...
13:30:30.786 WIF: Checking connection...
13:30:50.769 WIF: Checking connection...
13:31:02.437 WIF: Sending Gratuitous ARP
13:31:10.796 WIF: Checking connection...
13:31:30.786 WIF: Checking connection...
13:31:50.767 WIF: Checking connection...
13:32:02.436 WIF: Sending Gratuitous ARP
13:32:10.795 WIF: Checking connection...
13:32:16.404 RSL: STATE = {"Time":"2021-12-14T13:32:16","Uptime":"0T00:05:16","UptimeSec":316,"Heap":29,"SleepMode":"Dynamic","Sleep":50,"LoadAvg":19,"MqttCount":0,"POWER":"ON","Wifi":{"AP":2,"SSId":"HLinc2","BSSId":"18:31:BF:36:A0:98","Channel":6,"Mode":"11g","RSSI":100,"Signal":-42,"LinkCount":1,"Downtime":"0T00:00:10"}}

--------------- CUT HERE FOR EXCEPTION DECODER ---------------

Exception (9):
epc1=0x40203b4c epc2=0x00000000 epc3=0x00000000 excvaddr=0x4014c9c9 depc=0x00000000

>>>stack>>>

ctx: cont
sp: 3ffffdc0 end: 3fffffc0 offset: 0190
3fffff50:  4024c8c5 00000001 3fff1580 4024c8ba  
3fffff60:  3fffdad0 3ffefac4 0004d8e5 40203b80  
3fffff70:  3fffdad0 00000000 00000002 4023c3ed  
3fffff80:  00000000 00000000 00000001 40100a50  
3fffff90:  3fffdad0 00000000 3fff1540 3fff1580  
3fffffa0:  3fffdad0 00000000 3fff1540 4024b644  
3fffffb0:  feefeffe feefeffe 3ffe86d4 40101e69  
<<<stack<<<

--------------- CUT HERE FOR EXCEPTION DECODER ---------------

 ets Jan  8 2013,rst cause:4, boot mode:(3,6)

wdt reset
load 0x4010f000, len 3584, room 16 
tail 0
chksum 0xb0
csum 0xb0
v3969889e
~ld

00:00:00.002 HDW: ESP8266EX
00:00:00.050 CFG: Loaded from flash at F5, Count 63
00:00:00.056 QPC: Count 1
00:00:00.061 ROT: Mode 1
00:00:00.062 SRC: Restart
00:00:00.065 Project tasmota - Tasmota Version 10.1.0.1(tasmota)-2_7_4_9(2021-12-12T20:09:35)
00:00:00.218 WIF: Checking connection...
00:00:00.218 WIF: Attempting connection...
00:00:01.161 WIF: Network (re)scan started...
00:00:01.162 WIF: Checking connection...
00:00:01.163 WIF: Attempting connection...
00:00:02.161 WIF: Checking connection...
00:00:02.162 WIF: Attempting connection...
00:00:03.161 WIF: Checking connection...
00:00:03.162 WIF: Attempting connection...
00:00:04.164 WIF: Network 0, AP2, SSId HLinc2, Channel 6, BSSId 18:31:BF:36:A0:98, RSSI -70, Encryption 1
00:00:04.165 WIF: Network 1, AP2, SSId HLinc2, Channel 6, BSSId E0:3F:49:EE:88:21, RSSI -80, Encryption 1
00:00:04.172 WIF: Network 2, AP1, SSId HLinc, Channel 10, BSSId 28:EE:52:C1:A4:52, RSSI -67, Encryption 1
00:00:04.181 WIF: Network 3, AP1, SSId HLinc, Channel 10, BSSId 28:EE:52:C1:D1:D6, RSSI -68, Encryption 1
00:00:04.191 WIF: Network 4, AP-, SSId HLinc3, Channel 11, BSSId 74:DA:88:95:8E:B6, RSSI -91, Encryption 1
00:00:04.537 WIF: Connecting to AP1 HLinc Channel 10 BSSId 28:EE:52:C1:A4:52 in mode 11g as HLso7...
00:00:04.538 WIF: Checking connection...
00:00:04.539 WIF: Attempting connection...
00:00:05.561 CFG: Saved to flash at F4, Count 64, Bytes 4096
00:00:05.798 WIF: Checking connection...
00:00:05.799 WIF: Attempting connection...
00:00:06.395 QPC: Reset
00:00:06.777 WIF: Checking connection...
00:00:06.779 WIF: Attempting connection...
00:00:07.777 WIF: Checking connection...
00:00:07.779 WIF: Connected
00:00:08.029 HTP: Web server active on HLso7 with IP address 192.168.2.90
00:00:08.328 APP: Boot Count 25
00:00:08.329 WIF: Sending Gratuitous ARP
00:00:08.330 NTP: Synch time...
00:00:08.441 RTC: UTC 2021-12-14T12:32:27, DST 2021-03-28T02:00:00, STD 2021-10-31T03:00:00
13:32:27.000 NTP: Synched
13:32:27.168 CFG: Saved to flash at FB, Count 65, Bytes 4096
13:32:28.018 RSL: INFO1 = {"Info1":{"Module":"Sonoff Basic","Version":"10.1.0.1(tasmota)","FallbackTopic":"cmnd/DVES_AF718E_fb/","GroupTopic":"cmnd/tasmotas/"}}
13:32:28.024 RSL: INFO2 = {"Info2":{"WebServerMode":"Admin","Hostname":"HLso7","IPAddress":"192.168.2.90"}}
13:32:28.035 RSL: INFO3 = {"Info3":{"RestartReason":{"Exception":9,"Reason":"Exception","EPC":["40203b4c","00000000","00000000"],"EXCVADDR":"4014c9c9","DEPC":"00000000","CallChain":["4024c8c5","4024c8ba","40203b80","4023c3ed","40100a50","4024b644","40101e69"]}}}
13:32:28.056 RSL: RESULT = {"POWER":"ON"}
13:32:28.059 RSL: POWER = ON
13:32:32.327 RSL: STATE = {"Time":"2021-12-14T13:32:32","Uptime":"0T00:00:15","UptimeSec":15,"Heap":29,"SleepMode":"Dynamic","Sleep":50,"LoadAvg":19,"MqttCount":0,"POWER":"ON","Wifi":{"AP":1,"SSId":"HLinc","BSSId":"28:EE:52:C1:A4:52","Channel":10,"Mode":"11g","RSSI":56,"Signal":-72,"LinkCount":1,"Downtime":"0T00:00:09"}}
13:32:46.786 WIF: Checking connection...
13:33:06.765 WIF: Checking connection...
13:33:19.331 WIF: Sending Gratuitous ARP
13:33:26.794 WIF: Checking connection...
13:33:46.782 WIF: Checking connection...
13:33:47.588 HTP: Main Menu
13:34:06.763 WIF: Checking connection...
13:34:19.309 WIF: Sending Gratuitous ARP
13:34:26.765 WIF: Checking connection...
13:34:46.785 WIF: Checking connection...
13:35:06.793 WIF: Checking connection...
13:35:19.317 WIF: Sending Gratuitous ARP
13:35:26.760 WIF: Checking connection...
13:35:46.782 WIF: Checking connection...
13:36:06.800 WIF: Checking connection...
13:36:19.318 WIF: Sending Gratuitous ARP
13:36:26.784 WIF: Checking connection...
13:36:46.765 WIF: Checking connection...
13:37:06.774 WIF: Checking connection...
13:37:19.307 WIF: Sending Gratuitous ARP
13:37:26.770 WIF: Checking connection...
13:37:32.315 RSL: STATE = {"Time":"2021-12-14T13:37:32","Uptime":"0T00:05:15","UptimeSec":315,"Heap":28,"SleepMode":"Dynamic","Sleep":50,"LoadAvg":19,"MqttCount":0,"POWER":"ON","Wifi":{"AP":1,"SSId":"HLinc","BSSId":"28:EE:52:C1:A4:52","Channel":10,"Mode":"11g","RSSI":52,"Signal":-74,"LinkCount":1,"Downtime":"0T00:00:09"}}

--------------- CUT HERE FOR EXCEPTION DECODER ---------------

Exception (0):
epc1=0x40203b3c epc2=0x00000000 epc3=0x00000000 excvaddr=0x4014c9c9 depc=0x00000000

>>>stack>>>

ctx: cont
sp: 3ffffdd0 end: 3fffffc0 offset: 0190
3fffff60:  3fffdad0 3ffefac4 0004eca5 40203b9d  
3fffff70:  3fffdad0 00000000 00000001 4023c3ed  
3fffff80:  00000000 00000000 00000001 40100a50  
3fffff90:  3fffdad0 00000000 3fff1540 3fff1580  
3fffffa0:  3fffdad0 00000000 3fff1540 4024b644  
3fffffb0:  feefeffe feefeffe 3ffe86d4 40101e69  
<<<stack<<<

--------------- CUT HERE FOR EXCEPTION DECODER ---------------

 ets Jan  8 2013,rst cause:4, boot mode:(3,6)

wdt reset
load 0x4010f000, len 3584, room 16 
tail 0
chksum 0xb0
csum 0xb0
v3969889e
~ld

00:00:00.002 HDW: ESP8266EX
00:00:00.050 CFG: Loaded from flash at FB, Count 65
00:00:00.056 QPC: Count 1
00:00:00.061 ROT: Mode 1
00:00:00.062 SRC: Restart
00:00:00.065 Project tasmota - Tasmota Version 10.1.0.1(tasmota)-2_7_4_9(2021-12-12T20:09:35)
00:00:00.218 WIF: Checking connection...
00:00:00.218 WIF: Attempting connection...
00:00:01.161 WIF: Network (re)scan started...
00:00:01.162 WIF: Checking connection...
00:00:01.162 WIF: Attempting connection...
00:00:02.162 WIF: Checking connection...
00:00:02.163 WIF: Attempting connection...
00:00:03.163 WIF: Checking connection...
00:00:03.164 WIF: Attempting connection...
00:00:04.164 WIF: Network 0, AP-, SSId Pals gast, Channel 1, BSSId B6:FB:E4:74:5E:56, RSSI -87, Encryption 1
00:00:04.165 WIF: Network 1, AP-, SSId Pals netwerk, Channel 1, BSSId B4:FB:E4:74:5E:56, RSSI -87, Encryption 1
00:00:04.173 WIF: Network 2, AP2, SSId HLinc2, Channel 6, BSSId 18:31:BF:36:A0:98, RSSI -42, Encryption 1
00:00:04.182 WIF: Network 3, AP2, SSId HLinc2, Channel 6, BSSId E0:3F:49:EE:88:21, RSSI -65, Encryption 1
00:00:04.192 WIF: Network 4, AP1, SSId HLinc, Channel 10, BSSId 28:EE:52:C1:D1:D6, RSSI -72, Encryption 1
00:00:04.201 WIF: Network 5, AP1, SSId HLinc, Channel 10, BSSId 28:EE:52:C1:A4:46, RSSI -89, Encryption 1
00:00:04.210 WIF: Network 6, AP1, SSId HLinc, Channel 10, BSSId 28:EE:52:C1:A4:52, RSSI -69, Encryption 1
00:00:04.556 WIF: Connecting to AP2 HLinc2 Channel 6 BSSId 18:31:BF:36:A0:98 in mode 11g as HLso7...
00:00:04.557 WIF: Checking connection...
00:00:04.558 WIF: Attempting connection...
00:00:05.563 CFG: Saved to flash at FA, Count 66, Bytes 4096
00:00:05.752 WIF: Checking connection...
00:00:05.754 WIF: Connected
00:00:06.004 HTP: Web server active on HLso7 with IP address 192.168.2.90
00:00:06.479 QPC: Reset
00:00:06.481 WIF: Sending Gratuitous ARP
00:00:06.483 NTP: Synch time...
00:00:06.521 RTC: UTC 2021-12-14T12:37:48, DST 2021-03-28T02:00:00, STD 2021-10-31T03:00:00
13:37:48.000 NTP: Synched
13:37:48.090 CFG: Saved to flash at F9, Count 67, Bytes 4096
13:37:49.023 RSL: INFO1 = {"Info1":{"Module":"Sonoff Basic","Version":"10.1.0.1(tasmota)","FallbackTopic":"cmnd/DVES_AF718E_fb/","GroupTopic":"cmnd/tasmotas/"}}
13:37:49.029 RSL: INFO2 = {"Info2":{"WebServerMode":"Admin","Hostname":"HLso7","IPAddress":"192.168.2.90"}}
13:37:49.040 RSL: INFO3 = {"Info3":{"RestartReason":{"Exception":0,"Reason":"Exception","EPC":["40203b3c","00000000","00000000"],"EXCVADDR":"4014c9c9","DEPC":"00000000","CallChain":["40203b9d","4023c3ed","40100a50","4024b644","40101e69"]}}}
13:37:49.059 RSL: RESULT = {"POWER":"ON"}
13:37:49.063 RSL: POWER = ON
13:37:50.421 APP: Boot Count 26
13:37:50.597 CFG: Saved to flash at F8, Count 68, Bytes 4096
13:37:53.420 RSL: STATE = {"Time":"2021-12-14T13:37:53","Uptime":"0T00:00:13","UptimeSec":13,"Heap":28,"SleepMode":"Dynamic","Sleep":50,"LoadAvg":19,"MqttCount":0,"POWER":"ON","Wifi":{"AP":2,"SSId":"HLinc2","BSSId":"18:31:BF:36:A0:98","Channel":6,"Mode":"11g","RSSI":100,"Signal":-48,"LinkCount":1,"Downtime":"0T00:00:07"}}
13:38:07.794 WIF: Checking connection...

 ets Jan  8 2013,rst cause:4, boot mode:(3,6)

wdt reset
load 0x4010f000, len 3584, room 16 
tail 0
chksum 0xb0
csum 0xb0
v3969889e
~ld

00:00:00.001 HDW: ESP8266EX
00:00:00.049 CFG: Loaded from flash at F8, Count 68
00:00:00.055 QPC: Count 1
00:00:00.060 ROT: Mode 1
00:00:00.061 SRC: Restart
00:00:00.064 Project tasmota - Tasmota Version 10.1.0.1(tasmota)-2_7_4_9(2021-12-12T20:09:35)
00:00:00.216 WIF: Checking connection...
00:00:00.217 WIF: Attempting connection...
00:00:01.164 WIF: Network (re)scan started...
00:00:01.165 WIF: Checking connection...
00:00:01.165 WIF: Attempting connection...
00:00:02.164 WIF: Checking connection...
00:00:02.165 WIF: Attempting connection...
00:00:03.165 WIF: Checking connection...
00:00:03.166 WIF: Attempting connection...
00:00:04.167 WIF: Network 0, AP-, SSId Pals gast, Channel 1, BSSId B6:FB:E4:74:5E:56, RSSI -89, Encryption 1
00:00:04.168 WIF: Network 1, AP-, SSId Pals netwerk, Channel 1, BSSId B4:FB:E4:74:5E:56, RSSI -86, Encryption 1
00:00:04.176 WIF: Network 2, AP2, SSId HLinc2, Channel 6, BSSId 18:31:BF:36:A0:98, RSSI -37, Encryption 1
00:00:04.185 WIF: Network 3, AP2, SSId HLinc2, Channel 6, BSSId E0:3F:49:EE:88:21, RSSI -73, Encryption 1
00:00:04.195 WIF: Network 4, AP1, SSId HLinc, Channel 10, BSSId 28:EE:52:C1:A4:52, RSSI -70, Encryption 1
00:00:04.204 WIF: Network 5, AP1, SSId HLinc, Channel 10, BSSId 28:EE:52:C1:A4:46, RSSI -89, Encryption 1
00:00:04.213 WIF: Network 6, AP1, SSId HLinc, Channel 10, BSSId 28:EE:52:C1:D1:D6, RSSI -71, Encryption 1
00:00:04.559 WIF: Connecting to AP2 HLinc2 Channel 6 BSSId 18:31:BF:36:A0:98 in mode 11g as HLso7...
00:00:04.560 WIF: Checking connection...
00:00:04.561 WIF: Attempting connection...
00:00:05.753 WIF: Checking connection...
00:00:05.755 WIF: Connected
00:00:06.005 HTP: Web server active on HLso7 with IP address 192.168.2.90
00:00:06.452 QPC: Reset
00:00:06.453 WIF: Sending Gratuitous ARP
00:00:06.455 NTP: Synch time...
00:00:06.480 RTC: UTC 2021-12-14T12:38:29, DST 2021-03-28T02:00:00, STD 2021-10-31T03:00:00
13:38:29.000 NTP: Synched
13:38:30.038 RSL: INFO1 = {"Info1":{"Module":"Sonoff Basic","Version":"10.1.0.1(tasmota)","FallbackTopic":"cmnd/DVES_AF718E_fb/","GroupTopic":"cmnd/tasmotas/"}}
13:38:30.043 RSL: INFO2 = {"Info2":{"WebServerMode":"Admin","Hostname":"HLso7","IPAddress":"192.168.2.90"}}
13:38:30.052 RSL: INFO3 = {"Info3":{"RestartReason":"Hardware Watchdog"}}
13:38:30.059 RSL: RESULT = {"POWER":"ON"}
13:38:30.062 RSL: POWER = ON


TO REPRODUCE

Restarted, now with MQTT active

EXPECTED BEHAVIOUR

A clear and concise description of what you expected to happen.

SCREENSHOTS

If applicable, add screenshots to help explain your problem.

ADDITIONAL CONTEXT

Add any other context about the problem here.

(Please, remember to close the issue when the problem has been addressed)

@ascillato
Copy link
Contributor

ascillato commented Dec 14, 2021

Hi,

There is no need to open new issues for the same problem. You can continue it in your own previous original issue. Doing that is good because in separated issues, it got complex for other users to follow your problem when doing a search.

Exception 0 sometimes is because the power source is not enough or it is a very noisy one. Just for testing and so as to discard a faulty regulator in your device, please, can you try disconnecting it from mains AC and powering directly to the 5Vcc rail or to the 3.3Vcc rail with a good power source?

@ascillato2 ascillato2 changed the title 14 hours after reflash with 10.1.0.1 (#14032) no web access, serial log shows Exception 9 and 0 Continued Troubleshooting from #14032 Dec 14, 2021
@ascillato2 ascillato2 added the troubleshooting Type - Troubleshooting label Dec 14, 2021
@Jason2866
Copy link
Collaborator

Do you have a second (spare) one? The issues you encounter looks like a defect device.
Sonoff Basic devices are known for dying young...
Most fail with a bad power supply, second most reason is faulty flash chip.

@Jason2866 Jason2866 added the Sonoff Basic R2 Specific Type - Issue specific to Sonoff Basic R2 label Dec 14, 2021
@Hans-Sh
Copy link
Author

Hans-Sh commented Dec 14, 2021

Hi,

There is no need to open new issues for the same problem. You can continue it in your own previous original issue. Doing that is good because in separated issues, it got complex for other users to follow your problem when doing a search.

Exception 0 sometimes is because the power source is not enough or it is a very noisy one. Just for testing and so as to discard a faulty regulator in your device, please, can you try disconnecting it from mains AC and powering directly to the 5Vcc rail or to the 3.3Vcc rail with a good power source?

Txs, The log provided is when it was(still is) connected Serial via PC USB bus AND the Sonoff it self is powered by a breadboard 3,3 Volt 1 Amp Power Supply.

@Hans-Sh
Copy link
Author

Hans-Sh commented Dec 14, 2021

Ok, will setup a Basic R3 to test in same conditions. Will keep you posted.

@Hans-Sh
Copy link
Author

Hans-Sh commented Dec 14, 2021

Have flashed (and erased) a Basic R3 with 10.1.0.1. Using Wifi Manager to set AP to HLinc2 (ASUS) failed. 6x power off/on to restart Wifi Manager. Set AP to HLinc (TP-Link network) and it connected easy to it in N mode.
Using the web interface to set AP2 to HLinc2 (ASUS) as this is my preferred setup to have a Wifi fallback in case of..
Restart, Device connect to initial used AP1 (Hlinc TP-Link). Did set So56 and So57 to 1 to get the wished behaviour.
Device did find the strongest AP (HLinc2 ASUS) as it is 1,5 meter away from it but times out. AND KEEPS TRYING TO CONNECT TO the strongest AP found.And fails, and fails, enless.
This gave me the opportunity to fiddle around with my ASUS router:

  • Disable 5 Ghz, Device keeps trying but can't connect
  • Reboot Asus, and it connects ..............

Back to the reported problem my thought is, as I configured the 2 AP's as above (my preferred setup) It will connect to ASUS if the router is fresh (re)started. If after hours it want/has to reconnect it ends up in this retry loop without success because it keeps connecting to the strongest AP (in my case the ASUS)

Wish, Is it possible to let it connect after X tries to the other AP?

Please let me know what to test /setup. I have a TP-Link 3x Deco M5 mesh and a Asus Mesh (AC2900 main/Rt68U client), several Sonoffs R2, R3, POW, HT16. I would be very pleased to be of any help in this annoying Wifi /Asus issue.

@barbudor
Copy link
Contributor

barbudor commented Dec 14, 2021

Wish, Is it possible to let it connect after X tries to the other AP?

wificonfig 4
but AFAIK you can't configure the number of retries before attempting the other one

@Hans-Sh
Copy link
Author

Hans-Sh commented Dec 14, 2021

Txs, did check. It is in wificonfig 4 as this is default but during this test the device was only trying to connect to the strongest and did not attempt to connect to the other configured AP.
19:29:11.976 CMD: wificonfig
19:29:11.977 SRC: Serial
19:29:11.979 CMD: Grp 0, Cmnd 'WIFICONFIG', Idx 1, Len 0, Data ''
19:29:11.981 RSL: RESULT = {"WifiConfig":{"4":"Retry"}}
19:29:18.012 WIF: Checking connection...

@ascillato
Copy link
Contributor

ascillato commented Dec 14, 2021

Please let me know what to test /setup. I have a TP-Link 3x Deco M5 mesh and a Asus Mesh (AC2900 main/Rt68U client), several Sonoffs R2, R3, POW, HT16. I would be very pleased to be of any help in this annoying Wifi /Asus issue.

Sorry, ASUS routers have issues with the wifi routines of Espressif SDK (that are closed source). This SDK is used by the Arduino Core and Tasmota is built on top of all that.

Please, check the discussion #13623 for extensive information about this and how to workaround it.

@ascillato2 ascillato2 removed the Sonoff Basic R2 Specific Type - Issue specific to Sonoff Basic R2 label Dec 14, 2021
@ascillato2 ascillato2 changed the title Continued Troubleshooting from #14032 Troubleshooting continuation from #14032 Dec 14, 2021
@Hans-Sh
Copy link
Author

Hans-Sh commented Dec 14, 2021

Please let me know what to test /setup. I have a TP-Link 3x Deco M5 mesh and a Asus Mesh (AC2900 main/Rt68U client), several Sonoffs R2, R3, POW, HT16. I would be very pleased to be of any help in this annoying Wifi /Asus issue.

Sorry, ASUS routers have issues with the wifi routines of Espressif SDK (that are closed source). This SDK is used by the Arduino Core and Tasmota is built on top of all that.

Please, check the discussion #13623 for extensive information about this and how to workaround it.

Mmm, pitty, I do not use any Ardino, Just a lot (20+) of Sonoffs. Is my wish (connect to other AP after x failed tries) part of Tasmota or also this SDK?

@ascillato2
Copy link
Collaborator

ascillato2 commented Dec 14, 2021

Arduino is the name of the Core that uses Tasmota (Not the board). Arduino is the code that have several low level functions. No matter which device you use, if you use Tasmota, under Tasmota, the functions are Arduino Core for ESP8266 board. This Core uses the radio routines provided by the manufacturer of the ESP8266 chip. Those are closed source and we can't fix them. So, all the issue with ASUS comes from the SDK (between other things is that the SDK don't have WMM that ASUS enforces as a MUST for 11n mode - other routers are relaxed about WMM requirements and that is why they work without issues).

@Hans-Sh
Copy link
Author

Hans-Sh commented Dec 14, 2021

Thanks for the explanation.
So my wish is for the Arduino SDK then? I think it is strange to have 2 AP's configure options and no fallback mechanism or is it how Tasmota uses the SDK?

@ascillato
Copy link
Contributor

ascillato commented Dec 14, 2021

What Tasmota does with WIFICONFIG being set to 4, is to try to connect to AP1. if AP1 is not available (meaning that it is not in the air), it will try to connect to AP2. Then, if Tasmota is connected to any AP and it disconnects, It will try to connect to the other configured AP.

With ASUS, the issue is that the initial handshake (while trying to connect) is performed but then ASUS asks for WMM and the SDK don't answer anything. So, from Tasmota side, it actually connects but in reality it doesn't.

So, the intended behavior for WIFICONFIG 4 is what you want, but the handshake (being managed in the SDK) is not correct.

You can:

  • Set your router to allow b/g/n
  • disable WMM
  • put a different SSID to 5Ghz and disable band steering
  • Set the command wifi 3 in Tasmota to disable mode 11n
  • Disable So56 and So57 and set Tasmota to connect to the router you already know has the higher strength.

Also, you can check everything discussed in #13623 for more workarounds in this known ASUS issue. Sorry.

@Hans-Sh
Copy link
Author

Hans-Sh commented Dec 14, 2021 via email

@Jason2866
Copy link
Collaborator

Closing since the original issue is solved -> hardware issue.

@Hans-Sh
Copy link
Author

Hans-Sh commented Dec 15, 2021

Sorry @Jason2866. It wasn't a hardware issue. It was caused by the known Arduino SDK / ASUS router Problem.
Workaround for me is to use Wifi 3 (if 9.5.0 and up) and wait for:
esp8266/Arduino#8319 and the Tasmota development thereafter.
Thanks for all who helped to analyse my issue and to have it related to the existing mentioned Arduino SDK (2.5.2 and higher) without doubts.

@Jason2866
Copy link
Collaborator

The PR esp8266/Arduino#8319 does not solve the Asus issues.
See tests in #13623 So at the end it is a hardware issue (replace ASUS) when you want to solve.

# for free to join this conversation on GitHub. Already have an account? # to comment
Labels
troubleshooting Type - Troubleshooting
Projects
None yet
Development

No branches or pull requests

5 participants