search

esphome / issues

Issue Tracker for ESPHome
https://esphome.io/
291 stars 34 forks source link

ESPHOME disconnects from Home assistant constantly (SOLVED w workaround) #1237

Closed jazzmonger closed 3 years ago

jazzmonger commented 4 years ago

@esphome, @OttoWinter SOLVED (hopefully): change all your ESP access points/routers to communicate using 20mhz channel width, NOT auto. See my Comment post below for more info:

https://github.com/esphome/issues/issues/1237#issuecomment-633061461

Im having the exact same issue as dozens of others here. At first I thought it was my new AP I installed yesterday, but all my other 2.4ghz devices are staying connected for hours. I've tried all different 802.11 standards to see if it was WiFi protocol related, but it's not (2.4ghz a/b/g/n all do the same thing).

my d1_mini_pro and nodemcuv2 connect fine and then about every 3 minutes they both disconnect and then immediately reconnect from the AP. I tried the loop "fix" @thedishking recommended to no avail.

This all seems very recent. It was rock solid until a week or so ago. I was on the beta channel until this AM. I'm now on 0.110.1

Update Available 🎉 Operating System 3.13 You are currently running version 4.6

ESPHome Current version: 1.14.3 (changelog)

I'm going to OS 3.13 to see if that helps. UPDATE: This did NOT help. Going back to .107 as suggested UPDATE: 0.107 has No change... same problems Very frustrating!!

Jeff

Operating environment/Installation (Hass.io/Docker/pip/etc.): HASSIO Home Assistant 0.110.1

arch armv7l
chassis embedded
dev false
docker true
hassio true
host_os HassOS 3.13
installation_type Home Assistant
os_name Linux
os_version 4.19.114-v7
python_version 3.7.7
supervisor 222
timezone America/Los_Angeles
version 0.110.1
virtualenv false

ESP (ESP32/ESP8266, Board/Sonoff): NODEMCU, D1 Mini PRO

Affected component:

Description of problem: Disconnections every 3 minutess or so

Problem-relevant YAML-configuration entries:

esphome:
  name: gatecontroller
  platform: ESP8266
  board: d1_mini_pro

  on_loop:
    then:
    - lambda: delay(5);
wifi:
  networks:
  - ssid:  "xx"
    password: "xxx"
  - ssid: "x"
    password: "xxx"

#  ssid: "xx Wifi"
#  password: "xxx"

  power_save_mode: none
  # ...
  manual_ip:
    # Set this to the IP of the ESP
    static_ip: 192.168.1.146
    gateway: 192.168.1.1
    subnet: 255.255.255.0
    dns1: 192.168.1.250
    dns2: 8.8.8.8
  # Enable fallback hotspot (captive portal) in case wifi connection fails
  ap:
    ssid: "Gate ESP Fallback hotspot"
    password: "XX"

captive_portal:
# Enable Home Assistant API
ota:
# Enable logging
logger:
# Enable Home Assistant API
api:

#GPIO00 = D3
#GPIO01 = XX
#GPIO02 = D4
#GPIO04 = D2
#GPIO05 = D1
#GPIO12 = D6
#GPIO13 = D7
#GPIO14 = D5
#GPIO16 = D0
#-----------------------------
switch:
  - platform: gpio
    pin: 5 #GPIO5, D1, pin 2
    inverted: no
    id: relay
    restore_mode: ALWAYS_OFF
  - platform: template
    name: "Gate Remote"
    icon: "mdi:gate"
    turn_on_action:
    - switch.turn_on: relay
    - delay: 1000ms
    - switch.turn_off: relay

#-----------------------------    

binary_sensor:
  - platform: gpio
    name: Gate Open SW
    pin:
      number: 0 #GPIO0, D3
      inverted: True
      mode: INPUT_PULLUP

sensor:
  - platform: uptime
    name: Gate Controller Uptime Sensor
    filters:
      - lambda: return x / 3600.0;
    unit_of_measurement: "hours"
    accuracy_decimals: 2
    update_interval: 600s #10 min

Logs (if applicable):

PASTE DEBUG LOG HERE OUTPUT FROM 0.110

[08:46:20][C][ota:029]: Over-The-Air Updates:
[08:46:20][C][ota:030]:   Address: 192.168.1.145:8266
[08:46:20][C][api:095]: API Server:
[08:46:20][C][api:096]:   Address: 192.168.1.145:6053
WARNING Disconnected from API: Timeout while waiting for message response!
INFO Connecting to 192.168.1.145:6053 (192.168.1.145)
WARNING Couldn't connect to API (Error connecting to 192.168.1.145: timed out). Trying to reconnect in 1 seconds
INFO Connecting to 192.168.1.145:6053 (192.168.1.145)
INFO Successfully connected to 192.168.1.145
[08:46:55][D][api.connection:602]: Client 'Home Assistant 0.110.1 (192.168.1.250)' connected successfully!
WARNING Disconnected from API: Timeout while waiting for message response!
INFO Connecting to 192.168.1.145:6053 (192.168.1.145)
WARNING Couldn't connect to API (Error connecting to 192.168.1.145: timed out). Trying to reconnect in 1 seconds
INFO Connecting to 192.168.1.145:6053 (192.168.1.145)
INFO Successfully connected to 192.168.1.145
[08:47:18][D][api.connection:602]: Client 'Home Assistant 0.110.1 (192.168.1.250)' connected successfully!
[08:47:19][D][api:067]: Disconnecting Home Assistant 0.110.1 (192.168.1.250)
[08:47:24][D][api:067]: Disconnecting Home Assistant 0.110.1 (192.168.1.250)
WARNING Disconnected from API: Timeout while waiting for message response!
INFO Connecting to 192.168.1.145:6053 (192.168.1.145)
WARNING Couldn't connect to API (Error connecting to 192.168.1.145: timed out). Trying to reconnect in 1 seconds
INFO Connecting to 192.168.1.145:6053 (192.168.1.145)
WARNING Couldn't connect to API (Error connecting to 192.168.1.145: timed out). Trying to reconnect in 1 seconds
INFO Connecting to 192.168.1.145:6053 (192.168.1.145)
WARNING Couldn't connect to API (Error connecting to 192.168.1.145: [Errno 113] No route to host). Trying to reconnect in 2 seconds
INFO Connecting to 192.168.1.145:6053 (192.168.1.145)
WARNING Couldn't connect to API (Error connecting to 192.168.1.145: [Errno 113] No route to host). Trying to reconnect in 3 seconds
INFO Connecting to 192.168.1.145:6053 (192.168.1.145)
INFO Successfully connected to 192.168.1.145
[08:48:18][D][api.connection:602]: Client 'Home Assistant 0.110.1 (192.168.1.250)' connected successfully!
WARNING Disconnected from API: Timeout while waiting for message response!
INFO Connecting to 192.168.1.145:6053 (192.168.1.145)
WARNING Couldn't connect to API (Error connecting to 192.168.1.145: timed out). Trying to reconnect in 1 seconds
INFO Connecting to 192.168.1.145:6053 (192.168.1.145)
WARNING Couldn't connect to API (Error connecting to 192.168.1.145: timed out). Trying to reconnect in 1 seconds
INFO Connecting to 192.168.1.145:6053 (192.168.1.145)
WARNING Couldn't connect to API (Error connecting to 192.168.1.145: [Errno 113] No route to host). Trying to reconnect in 2 seconds
INFO Connecting to 192.168.1.145:6053 (192.168.1.145)
WARNING Couldn't connect to API (Error connecting to 192.168.1.145: [Errno 113] No route to host). Trying to reconnect in 3 seconds
INFO Connecting to 192.168.1.145:6053 (192.168.1.145)
WARNING Couldn't connect to API (Error connecting to 192.168.1.145: [Errno 113] No route to host). Trying to reconnect in 5 seconds
INFO Connecting to 192.168.1.145:6053 (192.168.1.145)
INFO Successfully connected to 192.168.1.145
[08:49:25][D][api.connection:602]: Client 'Home Assistant 0.110.1 (192.168.1.250)' connected successfully!
[08:49:32][D][api:067]: Disconnecting Home Assistant 0.110.1 (192.168.1.250)
WARNING Disconnected from API: Timeout while waiting for message response!
INFO Connecting to 192.168.1.145:6053 (192.168.1.145)
WARNING Couldn't connect to API (Error connecting to 192.168.1.145: timed out). Trying to reconnect in 1 seconds
INFO Connecting to 192.168.1.145:6053 (192.168.1.145)
INFO Successfully connected to 192.168.1.145
[08:50:10][D][api:067]: Disconnecting Home Assistant 0.110.1 (192.168.1.250)
[08:51:07][D][sensor:092]: 'Coop ESP8266 Upensor': Sending state 1.27334 hours with 0 decimals of accuracy

OUTPUT FROM 0.107 WARNING Disconnected from API: Timeout while waiting for message response! INFO Connecting to 192.168.1.145:6053 (192.168.1.145) WARNING Couldn't connect to API (Error connecting to 192.168.1.145: timed out). Trying to reconnect in 1 seconds INFO Connecting to 192.168.1.145:6053 (192.168.1.145) INFO Successfully connected to 192.168.1.145 [09:38:25][D][api.connection:602]: Client 'Home Assistant 0.107.4 (192.168.1.250)' connected successfully! WARNING Disconnected from API: Timeout while waiting for message response! INFO Connecting to 192.168.1.145:6053 (192.168.1.145) WARNING Couldn't connect to API (Error connecting to 192.168.1.145: timed out). Trying to reconnect in 1 seconds INFO Connecting to 192.168.1.145:6053 (192.168.1.145) INFO Successfully connected to 192.168.1.145 [09:39:45][D][api.connection:602]: Client 'Home Assistant 0.107.4 (192.168.1.250)' connected successfully! WARNING Disconnected from API: Timeout while waiting for message response! INFO Connecting to 192.168.1.145:6053 (192.168.1.145) WARNING Couldn't connect to API (Error connecting to 192.168.1.145: timed out). Trying to reconnect in 1 seconds INFO Connecting to 192.168.1.145:6053 (192.168.1.145) INFO Successfully connected to 192.168.1.145 [09:40:11][D][api:067]: Disconnecting Home Assistant 0.107.4 (192.168.1.250) [09:40:13][D][api.connection:602]: Client 'Home Assistant 0.107.4 (192.168.1.250)' connected successfully! [09:40:15][D][api:067]: Disconnecting Home Assistant 0.107.4 (192.168.1.250)


**Additional information and things you've tried:**

changing WiFi to 802.11a, b, g, n individually has no effect as does changing to a 
new Ubiquity Loco M2 AP.  All other 2.4ghz devices work just fine and
 stay connected for hours.

<!-- LEAVE THIS LINE AS-IS AND DON'T DELETE IT, OTHERWISE 
THE ISSUE WILL BE CLOSED AUTOMATICALLY. -->
jazzmonger commented 4 years ago

More info. Watching 2 device logs indicates that the ESP's disconnect and reconnect at the same times. This would indeed imply that that the problem is on the HOME ASSISTANT side, not the ESP side or in the Wifi chain:

COOP NODE MCU ESP

INFO Reading configuration /config/esphome/coop.yaml...
INFO Starting log output from 192.168.1.145 using esphome API
INFO Connecting to 192.168.1.145:6053 (192.168.1.145)
INFO Successfully connected to 192.168.1.145
[11:25:32][I][app:101]: ESPHome version 1.15.0-dev compiled on Apr 13 2020, 08:28:28
[11:25:32][C][wifi:415]: WiFi:
[11:25:32][C][wifi:283]:   SSID: 'xx'[redacted]
[11:25:32][C][wifi:284]:   IP Address: 192.168.1.145
[11:25:32][C][wifi:286]:   BSSID: 00:0E:3B:81:01:04[redacted]
[11:25:32][C][wifi:287]:   Hostname: 'coop'
[11:25:32][C][wifi:291]:   Signal strength: -59 dB ▂▄▆█
[11:25:32][C][wifi:295]:   Channel: 8
[11:25:32][C][wifi:296]:   Subnet: 255.255.255.0
[11:25:32][C][wifi:297]:   Gateway: 192.168.1.1
[11:25:32][C][wifi:298]:   DNS1: 192.168.1.250
[11:25:32][C][wifi:299]:   DNS2: (IP unset)
[11:25:32][C][gpio.binary_sensor:015]: GPIO Binary Sensor 'Coop Bottom Button'
[11:25:32][C][gpio.binary_sensor:016]:   Pin: GPIO0 (Mode: INPUT_PULLUP, INVERTED)
[11:25:32][C][gpio.binary_sensor:015]: GPIO Binary Sensor 'Coop Top Button'
[11:25:32][C][gpio.binary_sensor:016]:   Pin: GPIO2 (Mode: INPUT_PULLUP, INVERTED)
[11:25:33][C][gpio.binary_sensor:015]: GPIO Binary Sensor 'Coop Outside Reed SW'
[11:25:33][C][gpio.binary_sensor:015]:   Device Class: 'door'
[11:25:33][C][gpio.binary_sensor:016]:   Pin: GPIO4 (Mode: INPUT_PULLUP)
[11:25:33][C][gpio.binary_sensor:015]: GPIO Binary Sensor 'Coop Inside Reed SW'
[11:25:33][C][gpio.binary_sensor:015]:   Device Class: 'door'
[11:25:33][C][gpio.binary_sensor:016]:   Pin: GPIO5 (Mode: INPUT_PULLUP)
[11:25:33][C][output.switch:009]: Output Switch 'Coop Door Inside'
[11:25:33][C][output.switch:009]: Output Switch 'Coop Door Outside'
[11:25:33][C][uptime.sensor:030]: Uptime Sensor 'Coop ESP8266 Upensor'
[11:25:33][C][uptime.sensor:030]:   Unit of Measurement: 'hours'
[11:25:33][C][uptime.sensor:030]:   Accuracy Decimals: 0
[11:25:33][C][uptime.sensor:030]:   Icon: 'mdi:timer'
[11:25:33][C][gpio.output:010]: GPIO Binary Output:
[11:25:33][C][gpio.output:011]:   Pin: GPIO12 (Mode: OUTPUT)
[11:25:33][C][gpio.output:012]:   Inverted: YES
[11:25:33][C][gpio.output:010]: GPIO Binary Output:
[11:25:33][C][gpio.output:011]:   Pin: GPIO14 (Mode: OUTPUT)
[11:25:33][C][gpio.output:012]:   Inverted: YES
[11:25:33][C][logger:175]: Logger:
[11:25:33][C][logger:176]:   Level: DEBUG
[11:25:33][C][logger:177]:   Log Baud Rate: 115200
[11:25:33][C][logger:178]:   Hardware UART: UART0
[11:25:33][C][captive_portal:169]: Captive Portal:
[11:25:33][C][ota:029]: Over-The-Air Updates:
[11:25:33][C][ota:030]:   Address: 192.168.1.145:8266
[11:25:33][C][api:095]: API Server:
[11:25:33][C][api:096]:   Address: 192.168.1.145:6053
WARNING Disconnected from API: Timeout while waiting for message response!
INFO Connecting to 192.168.1.145:6053 (192.168.1.145)
WARNING Couldn't connect to API (Error connecting to 192.168.1.145: timed out). Trying to reconnect in 1 seconds
INFO Connecting to 192.168.1.145:6053 (192.168.1.145)
INFO Successfully connected to 192.168.1.145
[11:26:01][D][api.connection:602]: Client 'Home Assistant 0.110.1 (192.168.1.250)' connected successfully!
WARNING Disconnected from API: Timeout while waiting for message response!
INFO Connecting to 192.168.1.145:6053 (192.168.1.145)
WARNING Couldn't connect to API (Error connecting to 192.168.1.145: timed out). Trying to reconnect in 1 seconds
INFO Connecting to 192.168.1.145:6053 (192.168.1.145)
WARNING Couldn't connect to API (Error connecting to 192.168.1.145: timed out). Trying to reconnect in 1 seconds
INFO Connecting to 192.168.1.145:6053 (192.168.1.145)
WARNING Couldn't connect to API (Error connecting to 192.168.1.145: timed out). Trying to reconnect in 2 seconds
INFO Connecting to 192.168.1.145:6053 (192.168.1.145)
WARNING Couldn't connect to API (Error connecting to 192.168.1.145: [Errno 113] No route to host). Trying to reconnect in 3 seconds
INFO Connecting to 192.168.1.145:6053 (192.168.1.145)
WARNING Couldn't connect to API (Error connecting to 192.168.1.145: [Errno 113] No route to host). Trying to reconnect in 5 seconds
INFO Connecting to 192.168.1.145:6053 (192.168.1.145)
WARNING Couldn't connect to API (Error connecting to 192.168.1.145: [Errno 113] No route to host). Trying to reconnect in 7 seconds
INFO Connecting to 192.168.1.145:6053 (192.168.1.145)
INFO Successfully connected to 192.168.1.145
WARNING Disconnected from API: Timeout while waiting for message response!
INFO Connecting to 192.168.1.145:6053 (192.168.1.145)
WARNING Couldn't connect to API (Error connecting to 192.168.1.145: timed out). Trying to reconnect in 1 seconds
INFO Connecting to 192.168.1.145:6053 (192.168.1.145)
INFO Successfully connected to 192.168.1.145
[11:28:11][D][api.connection:602]: Client 'Home Assistant 0.110.1 (192.168.1.250)' connected successfully!

GATE D1 Mini ESP

INFO Reading configuration /config/esphome/gatecontroller.yaml...
INFO Starting log output from 192.168.1.146 using esphome API
INFO Connecting to 192.168.1.146:6053 (192.168.1.146)
INFO Successfully connected to 192.168.1.146
[11:25:33][I][app:101]: ESPHome version 1.15.0-dev compiled on May 22 2020, 10:28:20
[11:25:33][C][wifi:415]: WiFi:
[11:25:33][C][wifi:283]:   SSID: 'xx'[redacted]
[11:25:33][C][wifi:284]:   IP Address: 192.168.1.146
[11:25:33][C][wifi:286]:   BSSID: 00:0E:3B:81:01:04[redacted]
[11:25:33][C][wifi:287]:   Hostname: 'gatecontroller'
[11:25:33][C][wifi:291]:   Signal strength: -66 dB ▂▄▆█
[11:25:33][C][wifi:295]:   Channel: 8
[11:25:33][C][wifi:296]:   Subnet: 255.255.255.0
[11:25:33][C][wifi:297]:   Gateway: 192.168.1.1
[11:25:33][C][wifi:298]:   DNS1: 192.168.1.250
[11:25:33][C][wifi:299]:   DNS2: 8.8.8.8
[11:25:33][C][switch.gpio:042]: GPIO Switch 'relay'
[11:25:33][C][switch.gpio:043]:   Pin: GPIO5 (Mode: OUTPUT)
[11:25:33][C][switch.gpio:059]:   Restore Mode: Always OFF
[11:25:33][C][template.switch:058]: Template Switch 'Gate Remote'
[11:25:33][C][template.switch:058]:   Icon: 'mdi:gate'
[11:25:33][C][template.switch:059]:   Restore State: NO
[11:25:33][C][template.switch:060]:   Optimistic: NO
[11:25:33][C][gpio.binary_sensor:015]: GPIO Binary Sensor 'Gate Open SW'
[11:25:33][C][gpio.binary_sensor:016]:   Pin: GPIO0 (Mode: INPUT_PULLUP, INVERTED)
[11:25:33][C][uptime.sensor:030]: Uptime Sensor 'Gate Controller Uptime Sensor'
[11:25:33][C][uptime.sensor:030]:   Unit of Measurement: 'hours'
[11:25:33][C][uptime.sensor:030]:   Accuracy Decimals: 2
[11:25:33][C][uptime.sensor:030]:   Icon: 'mdi:timer'
[11:25:33][C][logger:175]: Logger:
[11:25:33][C][logger:176]:   Level: DEBUG
[11:25:33][C][logger:177]:   Log Baud Rate: 115200
[11:25:33][C][logger:178]:   Hardware UART: UART0
[11:25:33][C][captive_portal:169]: Captive Portal:
[11:25:33][C][ota:029]: Over-The-Air Updates:
[11:25:33][C][ota:030]:   Address: 192.168.1.146:8266
[11:25:33][C][api:095]: API Server:
[11:25:33][C][api:096]:   Address: 192.168.1.146:6053
WARNING Disconnected from API: Timeout while waiting for message response!
INFO Connecting to 192.168.1.146:6053 (192.168.1.146)
WARNING Couldn't connect to API (Error connecting to 192.168.1.146: timed out). Trying to reconnect in 1 seconds
INFO Connecting to 192.168.1.146:6053 (192.168.1.146)
INFO Successfully connected to 192.168.1.146
[11:26:01][D][api.connection:599]: Client 'Home Assistant 0.110.1 (192.168.1.250)' connected successfully!
WARNING Disconnected from API: Timeout while waiting for message response!
INFO Connecting to 192.168.1.146:6053 (192.168.1.146)
WARNING Couldn't connect to API (Error connecting to 192.168.1.146: timed out). Trying to reconnect in 1 seconds
INFO Connecting to 192.168.1.146:6053 (192.168.1.146)
WARNING Couldn't connect to API (Error connecting to 192.168.1.146: timed out). Trying to reconnect in 1 seconds
INFO Connecting to 192.168.1.146:6053 (192.168.1.146)
WARNING Couldn't connect to API (Error connecting to 192.168.1.146: timed out). Trying to reconnect in 2 seconds
INFO Connecting to 192.168.1.146:6053 (192.168.1.146)
WARNING Couldn't connect to API (Error connecting to 192.168.1.146: [Errno 113] No route to host). Trying to reconnect in 3 seconds
INFO Connecting to 192.168.1.146:6053 (192.168.1.146)
WARNING Couldn't connect to API (Error connecting to 192.168.1.146: [Errno 113] No route to host). Trying to reconnect in 5 seconds
INFO Connecting to 192.168.1.146:6053 (192.168.1.146)
WARNING Couldn't connect to API (Error connecting to 192.168.1.146: [Errno 113] No route to host). Trying to reconnect in 7 seconds
INFO Connecting to 192.168.1.146:6053 (192.168.1.146)
INFO Successfully connected to 192.168.1.146
[11:27:06][D][api:067]: Disconnecting ESPHome v1.15.0-dev (192.168.1.250)
WARNING Disconnected from API: Timeout while waiting for message response!
INFO Connecting to 192.168.1.146:6053 (192.168.1.146)
WARNING Couldn't connect to API (Error connecting to 192.168.1.146: timed out). Trying to reconnect in 1 seconds
INFO Connecting to 192.168.1.146:6053 (192.168.1.146)
INFO Successfully connected to 192.168.1.146
[11:28:09][D][api:067]: Disconnecting Home Assistant 0.110.1 (192.168.1.250)
[11:28:11][D][api.connection:599]: Client 'Home Assistant 0.110.1 (192.168.1.250)' connected successfully!
jazzmonger commented 4 years ago

More info from the home assistant log file:

2020-05-22 11:31:02 INFO (MainThread) [homeassistant.components.esphome] Can't connect to ESPHome API for 192.168.1.155: Error connecting to ('192.168.1.155', 6053): [Errno 113] Connect call failed ('192.168.1.155', 6053)
2020-05-22 11:31:02 INFO (MainThread) [homeassistant.components.esphome] Trying to reconnect in 60 seconds
2020-05-22 11:31:35 WARNING (MainThread) [homeassistant.util.async_] Detected I/O inside the event loop. This is causing stability issues. Please report issue to the custom component author for sonoff doing I/O at custom_components/sonoff/__init__.py, line 374: ), headers=self._headers)
2020-05-22 11:31:37 WARNING (SyncWorker_12) [amcrest.http] <None:None> Trying again due to error: ConnectionError(MaxRetryError("HTTPConnectionPool(host='192.168.1.87', port=80): Max retries exceeded with url: /cgi-bin/magicBox.cgi?action=getMachineName (Caused by NewConnectionError('<urllib3.connection.HTTPConnection object at ADDRESS>: Failed to establish a new connection: [Errno 113] Host is unreachable'))"))
2020-05-22 11:31:40 INFO (MainThread) [aioesphomeapi.connection] 192.168.1.145: Ping Failed!
2020-05-22 11:31:40 INFO (MainThread) [homeassistant.components.esphome] Disconnected from ESPHome API for 192.168.1.145
2020-05-22 11:31:40 INFO (MainThread) [aioesphomeapi.connection] 192.168.1.145: Error while reading incoming messages: Error while receiving data: 0 bytes read on a total of 1 expected bytes
2020-05-22 11:31:53 INFO (MainThread) [aioesphomeapi.connection] 192.168.1.146: Ping Failed!
2020-05-22 11:31:54 INFO (MainThread) [homeassistant.components.esphome] Disconnected from ESPHome API for 192.168.1.146
2020-05-22 11:31:54 INFO (MainThread) [aioesphomeapi.connection] 192.168.1.146: Error while reading incoming messages: Error while receiving data: 0 bytes read on a total of 1 expected bytes
2020-05-22 11:31:54 ERROR (MainThread) [aioharmony.hubconnector] 192.168.1.122: Connection timed out for hub 10785974
2020-05-22 11:31:56 INFO (MainThread) [homeassistant.components.esphome] Successfully connected to 192.168.1.145
2020-05-22 11:31:57 INFO (MainThread) [homeassistant.components.esphome] Successfully connected to 192.168.1.146
2020-05-22 11:32:05 INFO (MainThread) [homeassistant.components.esphome] Can't connect to ESPHome API for 192.168.1.155: Error connecting to ('192.168.1.155', 6053): [Errno 113] Connect call failed ('192.168.1.155', 6053)
2020-05-22 11:32:05 INFO (MainThread) [homeassistant.components.esphome] Trying to reconnect in 60 seconds
2020-05-22 11:32:08 ERROR (MainThread) [homeassistant.components.ipp] Error fetching ipp data: Invalid response from API: Error occurred while communicating with IPP server.
2020-05-22 11:32:08 WARNING (MainThread) [homeassistant.config_entries] Config entry for ipp not ready yet. Retrying in 80 seconds.
2020-05-22 11:32:12 ERROR (MainThread) [aioharmony.hubconnector] 192.168.1.103: Exception on post: Cannot connect to host 192.168.1.103:8088 ssl:None [Connect call failed ('192.168.1.103', 8088)]
2020-05-22 11:32:12 ERROR (MainThread) [aioharmony.hubconnector] 192.168.1.103: Unable to retrieve HUB id
2020-05-22 11:32:12 WARNING (MainThread) [homeassistant.components.harmony.remote] Master Bedroom: Unable to connect to HUB.
2020-05-22 11:32:12 WARNING (MainThread) [homeassistant.config_entries] Config entry for harmony not ready yet. Retrying in 80 seconds.
jazzmonger commented 4 years ago

OK - I just had a major discovery. On a hunch from looking at [aioesphomeapi.connection] 192.168.1.145: Ping Failed! I set up a ping to each ESP from my Mac. Each of them stops responding at exactly the same time. They also disconnect from my access point at pretty much exactly the same time. so, this would point to the ESPs themselves, NOT HOME ASSISTANT !!!

Ive restarted the AP they both connect to several times, so they are connecting at the same time and disconnecting at the same time.

Firemogle commented 4 years ago

INFO Reading configuration /config/esphome/kitchen_leak.yaml... WARNING The esphomeyaml section has been renamed to esphome in 1.11.0. Please replace 'esphomeyaml:' in your configuration with 'esphome:'. INFO Starting log output from kitchen_leak.local using esphome API WARNING Exception occurred: Traceback (most recent call last): File "/usr/local/lib/python3.6/dist-packages/esphome/zeroconf.py", line 486, in handleread data, (addr, port) = socket.recvfrom(_MAX_MSG_ABSOLUTE) OSError: [Errno 9] Bad file descriptor INFO Connecting to kitchen_leak.local:6053 (192.168.2.113) INFO Successfully connected to kitchen_leak.local [15:13:30][I][app:101]: ESPHome version 1.15.0-dev compiled on May 19 2020, 12:56:51

[15:13:30][C][wifi:283]: SSID: [redacted] [15:13:30][C][wifi:284]: IP Address: 192.168.2.113 [15:13:30][C][wifi:286]: BSSID: [redacted]

[15:13:30][C][wifi:291]: Signal strength: -76 dB ▂▄▆█ [15:13:30][C][wifi:295]: Channel: 11 [15:13:30][C][wifi:296]: Subnet: 255.255.255.0 [15:13:30][C][wifi:297]: Gateway: 192.168.2.1 [15:13:30][C][wifi:298]: DNS1: 192.168.2.1

[15:13:30][C][template.binary_sensor:018]: Template Binary Sensor 'kitchen_leak' [15:13:30][C][template.binary_sensor:018]: Device Class: 'moisture'

[15:13:30][C][logger:176]: Level: DEBUG [15:13:30][C][logger:177]: Log Baud Rate: 115200 [15:13:30](Message skipped because it was too big to fit in TCP buffer - This is only cosmetic) [15:13:30](Message skipped because it was too big to fit in TCP buffer - This is only cosmetic) [15:13:30](Message skipped because it was too big to fit in TCP buffer - This is only cosmetic) [15:13:30](Message skipped because it was too big to fit in TCP buffer - This is only cosmetic) [15:13:30](Message skipped because it was too big to fit in TCP buffer - This is only cosmetic) [15:13:30](Message skipped because it was too big to fit in TCP buffer - This is only cosmetic) [15:13:30][C][adc:051]: Update Interval: 60.0s [15:13:30][C][ota:029]: Over-The-Air Updates: [15:13:30][C][ota:030]: Address: kitchen_leak.local:8266 [15:13:30][C][ota:032]: Using Password. [15:13:30][C][api:095]: API Server: [15:13:30][C][api:096]: Address: kitchen_leak.local:6053 [15:13:50][D][adc:056]: 'kitchen_leak_raw': Got voltage=0.00V [15:13:51][D][sensor:092]: 'kitchen_leak_raw': Sending state 0.00645 V with 2 decimals of accuracy [15:14:51][D][adc:056]: 'kitchen_leak_raw': Got voltage=0.00V [15:14:51][D][sensor:092]: 'kitchen_leak_raw': Sending state 0.00645 V with 2 decimals of accuracy [15:15:50][D][adc:056]: 'kitchen_leak_raw': Got voltage=0.00V [15:15:50][D][sensor:092]: 'kitchen_leak_raw': Sending state 0.00645 V with 2 decimals of accuracy [15:16:50][D][adc:056]: 'kitchen_leak_raw': Got voltage=0.00V [15:16:52][D][sensor:092]: 'kitchen_leak_raw': Sending state 0.00967 V with 2 decimals of accuracy [15:17:50][D][adc:056]: 'kitchen_leak_raw': Got voltage=0.00V [15:17:50][D][sensor:092]: 'kitchen_leak_raw': Sending state 0.00967 V with 2 decimals of accuracy WARNING Disconnected from API: Timeout while waiting for message response! INFO Connecting to kitchen_leak.local:6053 (192.168.2.113) WARNING Couldn't connect to API (Error connecting to 192.168.2.113: timed out). Trying to reconnect in 1 seconds INFO Connecting to kitchen_leak.local:6053 (192.168.2.113) INFO Successfully connected to kitchen_leak.local [15:19:07][D][api.connection:599]: Client 'Home Assistant 0.109.6 (192.168.2.2)' connected successfully! [15:19:50][D][adc:056]: 'kitchen_leak_raw': Got voltage=0.00V [15:19:51][D][sensor:092]: 'kitchen_leak_raw': Sending state 0.00967 V with 2 decimals of accuracy [15:20:51][D][adc:056]: 'kitchen_leak_raw': Got voltage=0.00V [15:20:53][D][sensor:092]: 'kitchen_leak_raw': Sending state 0.00967 V with 2 decimals of accuracy

INFO Reading configuration /config/esphome/mini_aquarium.yaml... INFO Starting log output from 192.168.2.112 using esphome API INFO Connecting to 192.168.2.112:6053 (192.168.2.112) INFO Successfully connected to 192.168.2.112 [15:14:41][I][app:101]: ESPHome version 1.15.0-dev compiled on May 18 2020, 12:48:48

[15:14:41][C][wifi:283]: SSID: [redacted] [15:14:41][C][wifi:284]: IP Address: 192.168.2.112 [15:14:41][C][wifi:286]: BSSID: [redacted]

[15:14:41][C][wifi:291]: Signal strength: -68 dB ▂▄▆█ [15:14:41][C][wifi:295]: Channel: 11 [15:14:41][C][wifi:296]: Subnet: 255.255.255.0 [15:14:41][C][wifi:297]: Gateway: 192.168.2.1 [15:14:41][C][wifi:298]: DNS1: 192.168.2.1 [15:14:41][C][wifi:299]: DNS2: 0.0.0.0 [15:14:41][C][i2c:028]: I2C Bus: [15:14:41][C][i2c:029]: SDA Pin: GPIO21 [15:14:41][C][i2c:030]: SCL Pin: GPIO22 [15:14:41][C][i2c:031]: Frequency: 50000 Hz [15:14:41][I][i2c:033]: Scanning i2c bus for active devices... [15:14:41][I][i2c:040]: Found i2c device at address 0x76

[15:14:41][C][a4988.stepper:022]: Step Pin: GPIO5 (Mode: OUTPUT) [15:14:41][C][a4988.stepper:023]: Dir Pin: GPIO4 (Mode: OUTPUT) [15:14:41][C][a4988.stepper:024]: Sleep Pin: GPIO25 (Mode: OUTPUT, INVERTED) [15:14:41][C][a4988.stepper:025]: Acceleration: 1000000 steps/s^2 [15:14:41][C][a4988.stepper:025]: Deceleration: 1000000 steps/s^2 [15:14:42][C][a4988.stepper:025]: Max Speed: 250 steps/s [15:14:42][C][ledc.output:032]: LEDC Output: [15:14:42][C][ledc.output:033]: Pin GPIO13 (Mode: OUTPUT) [15:14:42][C][ledc.output:034]: LEDC Channel: 0 [15:14:42][C][ledc.output:035]: Frequency: 1000.0 Hz [15:14:42][C][ledc.output:032]: LEDC Output: [15:14:42][C][ledc.output:033]: Pin GPIO14 (Mode: OUTPUT) [15:14:42][C][ledc.output:034]: LEDC Channel: 1 [15:14:42][C][ledc.output:035]: Frequency: 1000.0 Hz [15:14:42][C][ledc.output:032]: LEDC Output: [15:14:42][C][ledc.output:033]: Pin GPIO15 (Mode: OUTPUT) [15:14:42][C][ledc.output:034]: LEDC Channel: 2 [15:14:42][C][ledc.output:035]: Frequency: 1000.0 Hz [15:14:42][C][ledc.output:032]: LEDC Output: [15:14:42][C][ledc.output:033]: Pin GPIO16 (Mode: OUTPUT) [15:14:42][C][ledc.output:034]: LEDC Channel: 3 [15:14:42][C][ledc.output:035]: Frequency: 1000.0 Hz [15:14:42][C][ledc.output:032]: LEDC Output: [15:14:42][C][ledc.output:033]: Pin GPIO17 (Mode: OUTPUT) [15:14:42][C][ledc.output:034]: LEDC Channel: 4 [15:14:42][C][ledc.output:035]: Frequency: 1000.0 Hz [15:14:42][C][ledc.output:032]: LEDC Output: [15:14:42][C][ledc.output:033]: Pin GPIO18 (Mode: OUTPUT) [15:14:42][C][ledc.output:034]: LEDC Channel: 5 [15:14:42][C][ledc.output:035]: Frequency: 1000.0 Hz [15:14:42][C][ledc.output:032]: LEDC Output: [15:14:42][C][ledc.output:033]: Pin GPIO23 (Mode: OUTPUT) [15:14:42][C][ledc.output:034]: LEDC Channel: 6 [15:14:42][C][ledc.output:035]: Frequency: 1000.0 Hz

[15:14:42][C][logger:176]: Level: DEBUG [15:14:42][C][logger:177]: Log Baud Rate: 115200 [15:14:42][C][logger:178]: Hardware UART: UART0

[15:14:42][C][light:179]: Default Transition Length: 1.0s [15:14:42][C][light:180]: Gamma Correct: 2.80

[15:14:42][C][light:179]: Default Transition Length: 1.0s [15:14:42][C][light:180]: Gamma Correct: 2.80

[15:14:42][C][light:179]: Default Transition Length: 1.0s [15:14:42][C][light:180]: Gamma Correct: 2.80

[15:14:42][C][light:179]: Default Transition Length: 1.0s [15:14:42][C][light:180]: Gamma Correct: 2.80 [15:14:42][C][adc:026]: ADC Sensor 'water_prob_v' [15:14:42][C][adc:026]: Unit of Measurement: 'V' [15:14:42][C][adc:026]: Accuracy Decimals: 2

[15:14:42][C][adc:035]: Pin: 32 [15:14:42][C][adc:047]: Attenuation: 11db (max 3.9V) [15:14:42][C][adc:051]: Update Interval: 60.0s [15:14:42][C][adc:026]: ADC Sensor 'bed_prob_v' [15:14:42][C][adc:026]: Unit of Measurement: 'V' [15:14:42][C][adc:026]: Accuracy Decimals: 2

[15:14:42][C][adc:035]: Pin: 33 [15:14:42][C][adc:047]: Attenuation: 11db (max 3.9V) [15:14:42][C][adc:051]: Update Interval: 60.0s [15:14:42][C][resistance:010]: Resistance Sensor 'tank_probe_r' [15:14:42][C][resistance:010]: Unit of Measurement: 'Ω' [15:14:42][C][resistance:010]: Accuracy Decimals: 1

[15:14:42][C][resistance:011]: Configuration: DOWNSTREAM [15:14:42][C][resistance:012]: Resistor: 10000.00Ω [15:14:42][C][resistance:013]: Reference Voltage: 3.3V [15:14:42][C][resistance:010]: Resistance Sensor 'bed_probe_r' [15:14:42][C][resistance:010]: Unit of Measurement: 'Ω' [15:14:42][C][resistance:010]: Accuracy Decimals: 1

[15:14:42][C][resistance:011]: Configuration: DOWNSTREAM [15:14:42][C][resistance:012]: Resistor: 10000.00Ω [15:14:42][C][resistance:013]: Reference Voltage: 3.3V [15:14:42][C][ntc:014]: NTC Sensor 'tank_probe_c' [15:14:42][C][ntc:014]: Unit of Measurement: '°C' [15:14:42][C][ntc:014]: Accuracy Decimals: 1

[15:14:42][C][ntc:014]: NTC Sensor 'bed_probe_c' [15:14:42][C][ntc:014]: Unit of Measurement: '°C' [15:14:42][C][ntc:014]: Accuracy Decimals: 1

[15:14:42][C][bme280.sensor:143]: Address: 0x76 [15:14:42][C][bme280.sensor:155]: IIR Filter: OFF [15:14:42][C][bme280.sensor:156]: Update Interval: 60.0s

[15:14:42][C][bme280.sensor:158]: Unit of Measurement: '°C' [15:14:42][C][bme280.sensor:158]: Accuracy Decimals: 1

[15:14:42][C][bme280.sensor:159]: Oversampling: 16x

[15:14:42][C][bme280.sensor:160]: Unit of Measurement: 'P' [15:14:42][C][bme280.sensor:160]: Accuracy Decimals: 1

[15:14:42][C][bme280.sensor:161]: Oversampling: 16x

[15:14:42][C][bme280.sensor:162]: Unit of Measurement: '%' [15:14:42][C][bme280.sensor:162]: Accuracy Decimals: 1

[15:14:42][C][bme280.sensor:163]: Oversampling: 16x [15:14:42][C][ota:029]: Over-The-Air Updates: [15:14:42][C][ota:030]: Address: 192.168.2.112:3232 [15:14:42][C][ota:032]: Using Password. [15:14:42][C][api:095]: API Server: [15:14:42][C][api:096]: Address: 192.168.2.112:6053 [15:14:42][C][homeassistant.binary_sensor:032]: Homeassistant Binary Sensor 'Feeder Toggle' [15:14:42][C][homeassistant.binary_sensor:033]: Entity ID: 'binary_sensor.mt_feeder_toggle' [15:14:56][D][adc:056]: 'water_prob_v': Got voltage=1.52V [15:14:57][D][sensor:092]: 'water_prob_v': Sending state 1.52381 V with 2 decimals of accuracy [15:14:57][D][resistance:037]: 'tank_probe_r' - Resistance 8579.1Ω [15:14:57][D][sensor:092]: 'tank_probe_r': Sending state 8579.08887 Ω with 1 decimals of accuracy [15:14:57][D][ntc:026]: 'tank_probe_c' - Temperature: 28.4°C [15:14:57][D][sensor:092]: 'tank_probe_c': Sending state 28.40224 °C with 1 decimals of accuracy [15:15:06][D][bme280.sensor:197]: Got temperature=31.9°C pressure=984.2hPa humidity=36.3% [15:15:06][D][sensor:092]: 'MiniTank Temperature': Sending state 31.88000 °C with 1 decimals of accuracy [15:15:06][D][sensor:092]: 'MiniTank Pressure': Sending state 984.16229 P with 1 decimals of accuracy [15:15:06][D][sensor:092]: 'MiniTank Humidity': Sending state 36.31348 % with 1 decimals of accuracy [15:15:13][D][adc:056]: 'bed_prob_v': Got voltage=1.51V [15:15:13][D][sensor:092]: 'bed_prob_v': Sending state 1.50667 V with 2 decimals of accuracy [15:15:13][D][resistance:037]: 'bed_probe_r' - Resistance 8401.5Ω [15:15:13][D][sensor:092]: 'bed_probe_r': Sending state 8401.48730 Ω with 1 decimals of accuracy [15:15:13][D][ntc:026]: 'bed_probe_c' - Temperature: 28.9°C [15:15:13][D][sensor:092]: 'bed_probe_c': Sending state 28.87266 °C with 1 decimals of accuracy [15:15:56][D][adc:056]: 'water_prob_v': Got voltage=1.51V [15:15:57][D][sensor:092]: 'water_prob_v': Sending state 1.50857 V with 2 decimals of accuracy [15:15:57][D][resistance:037]: 'tank_probe_r' - Resistance 8421.1Ω [15:15:57][D][sensor:092]: 'tank_probe_r': Sending state 8421.05371 Ω with 1 decimals of accuracy [15:15:57][D][ntc:026]: 'tank_probe_c' - Temperature: 28.8°C [15:15:57][D][sensor:092]: 'tank_probe_c': Sending state 28.82027 °C with 1 decimals of accuracy [15:16:06][D][bme280.sensor:197]: Got temperature=31.9°C pressure=984.1hPa humidity=36.2% [15:16:06][D][sensor:092]: 'MiniTank Temperature': Sending state 31.86000 °C with 1 decimals of accuracy [15:16:06][D][sensor:092]: 'MiniTank Pressure': Sending state 984.14752 P with 1 decimals of accuracy [15:16:06][D][sensor:092]: 'MiniTank Humidity': Sending state 36.21094 % with 1 decimals of accuracy [15:16:14][D][adc:056]: 'bed_prob_v': Got voltage=1.49V [15:16:14][D][sensor:092]: 'bed_prob_v': Sending state 1.49238 V with 2 decimals of accuracy [15:16:14][D][resistance:037]: 'bed_probe_r' - Resistance 8256.1Ω [15:16:14][D][sensor:092]: 'bed_probe_r': Sending state 8256.05957 Ω with 1 decimals of accuracy [15:16:14][D][ntc:026]: 'bed_probe_c' - Temperature: 29.3°C [15:16:14][D][sensor:092]: 'bed_probe_c': Sending state 29.26645 °C with 1 decimals of accuracy [15:16:56][D][adc:056]: 'water_prob_v': Got voltage=1.51V [15:16:57][D][sensor:092]: 'water_prob_v': Sending state 1.50857 V with 2 decimals of accuracy [15:16:57][D][resistance:037]: 'tank_probe_r' - Resistance 8421.1Ω [15:16:57][D][sensor:092]: 'tank_probe_r': Sending state 8421.05371 Ω with 1 decimals of accuracy [15:16:57][D][ntc:026]: 'tank_probe_c' - Temperature: 28.8°C [15:16:57][D][sensor:092]: 'tank_probe_c': Sending state 28.82027 °C with 1 decimals of accuracy [15:17:06][D][bme280.sensor:197]: Got temperature=31.8°C pressure=984.2hPa humidity=36.2% [15:17:06][D][sensor:092]: 'MiniTank Temperature': Sending state 31.82000 °C with 1 decimals of accuracy [15:17:06][D][sensor:092]: 'MiniTank Pressure': Sending state 984.15649 P with 1 decimals of accuracy [15:17:06][D][sensor:092]: 'MiniTank Humidity': Sending state 36.18555 % with 1 decimals of accuracy [15:17:13][D][adc:056]: 'bed_prob_v': Got voltage=1.50V [15:17:13][D][sensor:092]: 'bed_prob_v': Sending state 1.50381 V with 2 decimals of accuracy [15:17:13][D][resistance:037]: 'bed_probe_r' - Resistance 8372.2Ω [15:17:13][D][sensor:092]: 'bed_probe_r': Sending state 8372.21680 Ω with 1 decimals of accuracy [15:17:13][D][ntc:026]: 'bed_probe_c' - Temperature: 29.0°C [15:17:13][D][sensor:092]: 'bed_probe_c': Sending state 28.95128 °C with 1 decimals of accuracy [15:17:56][D][adc:056]: 'water_prob_v': Got voltage=1.50V [15:17:57][D][sensor:092]: 'water_prob_v': Sending state 1.49905 V with 2 decimals of accuracy [15:17:57][D][resistance:037]: 'tank_probe_r' - Resistance 8323.6Ω [15:17:57][D][sensor:092]: 'tank_probe_r': Sending state 8323.63867 Ω with 1 decimals of accuracy [15:17:57][D][ntc:026]: 'tank_probe_c' - Temperature: 29.1°C [15:17:57][D][sensor:092]: 'tank_probe_c': Sending state 29.08247 °C with 1 decimals of accuracy [15:18:06][D][bme280.sensor:197]: Got temperature=31.8°C pressure=984.2hPa humidity=36.2% [15:18:06][D][sensor:092]: 'MiniTank Temperature': Sending state 31.81000 °C with 1 decimals of accuracy [15:18:06][D][sensor:092]: 'MiniTank Pressure': Sending state 984.15594 P with 1 decimals of accuracy [15:18:06][D][sensor:092]: 'MiniTank Humidity': Sending state 36.20703 % with 1 decimals of accuracy [15:18:13][D][adc:056]: 'bed_prob_v': Got voltage=1.50V [15:18:13][D][sensor:092]: 'bed_prob_v': Sending state 1.50286 V with 2 decimals of accuracy [15:18:13][D][resistance:037]: 'bed_probe_r' - Resistance 8362.5Ω [15:18:13][D][sensor:092]: 'bed_probe_r': Sending state 8362.48145 Ω with 1 decimals of accuracy [15:18:13][D][ntc:026]: 'bed_probe_c' - Temperature: 29.0°C [15:18:13][D][sensor:092]: 'bed_probe_c': Sending state 28.97750 °C with 1 decimals of accuracy ERROR Error while reading incoming messages: Error while receiving data: [Errno 104] Connection reset by peer WARNING Disconnected from API: Error while receiving data: [Errno 104] Connection reset by peer INFO Connecting to 192.168.2.112:6053 (192.168.2.112) WARNING Couldn't connect to API (Timeout while waiting for message response!). Trying to reconnect in 1 seconds INFO Connecting to 192.168.2.112:6053 (192.168.2.112) INFO Successfully connected to 192.168.2.112 [15:18:56][D][adc:056]: 'water_prob_v': Got voltage=1.52V [15:18:57][D][sensor:092]: 'water_prob_v': Sending state 1.51619 V with 2 decimals of accuracy [15:18:57][D][resistance:037]: 'tank_probe_r' - Resistance 8499.7Ω [15:18:57][D][sensor:092]: 'tank_probe_r': Sending state 8499.73340 Ω with 1 decimals of accuracy [15:18:57][D][ntc:026]: 'tank_probe_c' - Temperature: 28.6°C [15:18:57][D][sensor:092]: 'tank_probe_c': Sending state 28.61103 °C with 1 decimals of accuracy [15:19:06][D][bme280.sensor:197]: Got temperature=31.8°C pressure=984.2hPa humidity=36.3%

Adding a small log of my disconnect, but the narrative on this issue so far sounds similar to mine, this is the output from 2 devices, one with only one sensor and one with several IO and they disconnect around the same time.

I noticed this on all the esp devices shortly after I ran several upgrades, router (pfsense) was moved to a new machine, home assistant to a new VM and updated. I have lots of variables but found all of my esps are doing similar as described.

n8hjp commented 4 years ago

Here is my addition to the list. I have 5 wemos D1s that are dropping in and out. I hooked one up the console on the RaspberryPi running the Home Assistant ESPhome integration one and am getting this output:

[22:19:17] [22:19:17] ets Jan 8 2013,rst cause:2, boot mode:(3,6) [22:19:17] [22:19:17]load 0x4010f000, len 1384, room 16 [22:19:17]tail 8 [22:19:17]chksum 0x2d [22:19:17]csum 0x2d [22:19:17]v8b899c12 [22:19:17]~ld [22:19:50] [22:19:50]Exception (0): WARNING Exception type: unknown [22:19:50]epc1=0x40249540 epc2=0x00000000 epc3=0x00000000 excvaddr=0x00000000 depc=0x00000000 WARNING Decoded 0x40249540: ic_get_ptk_alg [22:19:50] [22:19:50]>>>stack>>> WARNING Found stack trace! Trying to decode it [22:19:50] [22:19:50]ctx: sys [22:19:50]sp: 3fffed70 end: 3fffffb0 offset: 01a0 [22:19:50]3fffef10: 00000000 3ffed506 4024787b 40246dcd
WARNING Decoded 0x4024787b: pp_attach WARNING Decoded 0x40246dcd: pp_tx_idle_timeout [22:19:50]3fffef20: 3ffeed78 3ffed4f0 3fffdcc0 3ffeb410
[22:19:50]3fffef30: 00000080 3ffeed78 00000000 3fffdcc0
[22:19:50]3fffef40: 4024671f 3fffdab0 00000000 00000001
WARNING Decoded 0x4024671f: ppPeocessRxPktHdr [22:19:50]3fffef50: 3ffeb410 40000f49 3fffdab0 40000f49
[22:19:50]3fffef60: 40000e19 40001878 00000002 3fffffb0
[22:19:50]3fffef70: 3fffff10 aa55aa55 000000ed 40104744
WARNING Decoded 0x40104744: call_user_start_local [22:19:50]3fffef80: 4010474a 00000002 3fffffb0 6c696f53
WARNING Decoded 0x4010474a: call_user_start_local [22:19:50]3fffef90: 4010000d 2068636e 75746153 69746172
WARNING Decoded 0x4010000d: call_user_start [22:19:50]3fffefa0: 40100908 3fffef3c 401008a9 3fffff28
WARNING Decoded 0x40100908: cont_ret at cont.S.o WARNING Decoded 0x401008a9: cont_continue at cont.S.o [22:19:50]3fffefb0: 3fffffc0 00000000 00000000 feefeffe
[22:19:50]3fffefc0: feefeffe feefeffe feefeffe feefeffe
[22:19:50]3fffefd0: feefeffe feefeffe feefeffe feefeffe
[22:19:50]3fffefe0: feefeffe feefeffe feefeffe feefeffe
[22:19:50]3fffeff0: feefeffe feefeffe feefeffe feefeffe
[22:19:50]3ffff000: feefeffe feefeffe feefeffe feefeffe
[22:19:50]3ffff010: feefeffe feefeffe feefeffe feefeffe
[22:19:50]3ffff020: feefeffe feefeffe feefeffe feefeffe
[22:19:50]3ffff030: feefeffe feefeffe feefeffe feefeffe
[22:19:50]3ffff040: feefeffe feefeffe feefeffe feefeffe
[22:19:50]3ffff050: feefeffe feefeffe feefeffe feefeffe
[22:19:50]3ffff060: feefeffe feefeffe feefeffe feefeffe
[22:19:50]3ffff070: feefeffe feefeffe feefeffe feefeffe
[22:19:50]3ffff080: feefeffe feefeffe feefeffe feefeffe
[22:19:50]3ffff090: feefeffe feefeffe feefeffe feefeffe
[22:19:50]3ffff0a0: feefeffe feefeffe feefeffe feefeffe
[22:19:50]3ffff0b0: feefeffe feefeffe feefeffe feefeffe
[22:19:50]3ffff0c0: feefeffe feefeffe feefeffe feefeffe
[22:19:50]3ffff0d0: feefeffe feefeffe feefeffe feefeffe
[22:19:50]3ffff0e0: feefeffe feefeffe feefeffe feefeffe
[22:19:50]3ffff0f0: feefeffe feefeffe feefeffe feefeffe
[22:19:50]3ffff100: feefeffe feefeffe feefeffe feefeffe
[22:19:50]3ffff110: feefeffe feefeffe feefeffe feefeffe
[22:19:50]3ffff120: feefeffe feefeffe feefeffe feefeffe
[22:19:50]3ffff130: feefeffe feefeffe feefeffe feefeffe
[22:19:50]3ffff140: feefeffe feefeffe feefeffe feefeffe
[22:19:50]3ffff150: feefeffe feefeffe feefeffe feefeffe
[22:19:50]3ffff160: feefeffe feefeffe feefeffe feefeffe
[22:19:50]3ffff170: feefeffe feefeffe feefeffe feefeffe
[22:19:50]3ffff180: feefeffe feefeffe feefeffe feefeffe
[22:19:50]3ffff190: feefeffe feefeffe feefeffe feefeffe
[22:19:50]3ffff1a0: feefeffe feefeffe feefeffe feefeffe
[22:19:50]3ffff1b0: feefeffe feefeffe feefeffe feefeffe
[22:19:50]3ffff1c0: feefeffe feefeffe feefeffe feefeffe
[22:19:50]3ffff1d0: feefeffe feefeffe feefeffe feefeffe
[22:19:50]3ffff1e0: feefeffe feefeffe feefeffe feefeffe
[22:19:50]3ffff1f0: feefeffe feefeffe feefeffe feefeffe
[22:19:50]3ffff200: feefeffe feefeffe feefeffe feefeffe
[22:19:50]3ffff210: feefeffe feefeffe feefeffe feefeffe
[22:19:50]3ffff220: feefeffe feefeffe feefeffe feefeffe
[22:19:50]3ffff230: feefeffe feefeffe feefeffe feefeffe
[22:19:50]3ffff240: feefeffe feefeffe feefeffe feefeffe
[22:19:50]3ffff250: feefeffe feefeffe feefeffe feefeffe
[22:19:50]3ffff260: feefeffe feefeffe feefeffe feefeffe
[22:19:50]3ffff270: feefeffe feefeffe feefeffe feefeffe
[22:19:50]3ffff280: feefeffe feefeffe feefeffe feefeffe
[22:19:50]3ffff290: feefeffe feefeffe feefeffe feefeffe
[22:19:50]3ffff2a0: feefeffe feefeffe feefeffe feefeffe
[22:19:50]3ffff2b0: feefeffe feefeffe feefeffe feefeffe
[22:19:50]3ffff2c0: feefeffe feefeffe feefeffe feefeffe
[22:19:50]3ffff2d0: feefeffe feefeffe feefeffe feefeffe
[22:19:50]3ffff2e0: feefeffe feefeffe feefeffe feefeffe
[22:19:50]3ffff2f0: feefeffe feefeffe feefeffe feefeffe
[22:19:50]3ffff300: feefeffe feefeffe feefeffe feefeffe
[22:19:50]3ffff310: feefeffe feefeffe feefeffe feefeffe
[22:19:50]3ffff320: feefeffe feefeffe feefeffe feefeffe
[22:19:50]3ffff330: feefeffe feefeffe feefeffe feefeffe
[22:19:50]3ffff340: feefeffe feefeffe feefeffe feefeffe
[22:19:50]3ffff350: feefeffe feefeffe feefeffe feefeffe
[22:19:50]3ffff360: feefeffe feefeffe feefeffe feefeffe
[22:19:50]3ffff370: feefeffe feefeffe feefeffe feefeffe
[22:19:50]3ffff380: feefeffe feefeffe feefeffe feefeffe
[22:19:50]3ffff390: feefeffe feefeffe feefeffe feefeffe
[22:19:50]3ffff3a0: feefeffe feefeffe feefeffe feefeffe
[22:19:50]3ffff3b0: feefeffe feefeffe feefeffe feefeffe
[22:19:50]3ffff3c0: feefeffe feefeffe feefeffe feefeffe
[22:19:50]3ffff3d0: feefeffe feefeffe feefeffe feefeffe
[22:19:50]3ffff3e0: feefeffe feefeffe feefeffe feefeffe
[22:19:50]3ffff3f0: feefeffe feefeffe feefeffe feefeffe
[22:19:50]3ffff400: feefeffe feefeffe feefeffe feefeffe
[22:19:50]3ffff410: feefeffe feefeffe feefeffe feefeffe
[22:19:50]3ffff420: feefeffe feefeffe feefeffe feefeffe
[22:19:50]3ffff430: feefeffe feefeffe feefeffe feefeffe
[22:19:50]3ffff440: feefeffe feefeffe feefeffe feefeffe
[22:19:50]3ffff450: feefeffe feefeffe feefeffe feefeffe
[22:19:50]3ffff460: feefeffe feefeffe feefeffe feefeffe
[22:19:50]3ffff470: feefeffe feefeffe feefeffe feefeffe
[22:19:50]3ffff480: feefeffe feefeffe feefeffe feefeffe
[22:19:50]3ffff490: feefeffe feefeffe feefeffe feefeffe
[22:19:50]3ffff4a0: feefeffe feefeffe feefeffe feefeffe
[22:19:50]3ffff4b0: feefeffe feefeffe feefeffe feefeffe
[22:19:50]3ffff4c0: feefeffe feefeffe feefeffe feefeffe
[22:19:50]3ffff4d0: feefeffe feefeffe feefeffe feefeffe
[22:19:50]3ffff4e0: feefeffe feefeffe feefeffe feefeffe
[22:19:51]3ffff4f0: feefeffe feefeffe feefeffe feefeffe
[22:19:51]3ffff500: feefeffe feefeffe feefeffe feefeffe
[22:19:51]3ffff510: feefeffe feefeffe feefeffe feefeffe
[22:19:51]3ffff520: feefeffe feefeffe feefeffe feefeffe
[22:19:51]3ffff530: feefeffe feefeffe feefeffe feefeffe
[22:19:51]3ffff540: feefeffe feefeffe feefeffe feefeffe
[22:19:51]3ffff550: feefeffe feefeffe feefeffe feefeffe
[22:19:51]3ffff560: feefeffe feefeffe feefeffe feefeffe
[22:19:51]3ffff570: feefeffe feefeffe feefeffe feefeffe
[22:19:51]3ffff580: feefeffe feefeffe feefeffe feefeffe
[22:19:51]3ffff590: feefeffe feefeffe feefeffe feefeffe
[22:19:51]3ffff5a0: feefeffe feefeffe feefeffe feefeffe
[22:19:51]3ffff5b0: feefeffe feefeffe feefeffe feefeffe
[22:19:51]3ffff5c0: feefeffe feefeffe feefeffe feefeffe
[22:19:51]3ffff5d0: feefeffe feefeffe feefeffe feefeffe
[22:19:51]3ffff5e0: feefeffe feefeffe feefeffe feefeffe
[22:19:51]3ffff5f0: feefeffe feefeffe feefeffe feefeffe
[22:19:51]3ffff600: feefeffe feefeffe feefeffe feefeffe
[22:19:51]3ffff610: feefeffe feefeffe feefeffe feefeffe
[22:19:51]3ffff620: feefeffe feefeffe feefeffe feefeffe
[22:19:51]3ffff630: feefeffe feefeffe feefeffe feefeffe
[22:19:51]3ffff640: feefeffe feefeffe feefeffe feefeffe
[22:19:51]3ffff650: feefeffe feefeffe feefeffe feefeffe
[22:19:51]3ffff660: feefeffe feefeffe feefeffe feefeffe
[22:19:51]3ffff670: feefeffe feefeffe feefeffe feefeffe
[22:19:51]3ffff680: feefeffe feefeffe feefeffe feefeffe
[22:19:51]3ffff690: feefeffe feefeffe feefeffe feefeffe
[22:19:51]3ffff6a0: feefeffe feefeffe feefeffe feefeffe
[22:19:51]3ffff6b0: feefeffe feefeffe feefeffe feefeffe
[22:19:51]3ffff6c0: feefeffe feefeffe feefeffe feefeffe
[22:19:51]3ffff6d0: feefeffe feefeffe feefeffe feefeffe
[22:19:51]3ffff6e0: feefeffe feefeffe feefeffe feefeffe
[22:19:51]3ffff6f0: feefeffe feefeffe feefeffe feefeffe
[22:19:51]3ffff700: feefeffe feefeffe feefeffe feefeffe
[22:19:51]3ffff710: feefeffe feefeffe feefeffe feefeffe
[22:19:51]3ffff720: feefeffe feefeffe feefeffe feefeffe
[22:19:51]3ffff730: feefeffe feefeffe feefeffe feefeffe
[22:19:51]3ffff740: feefeffe feefeffe feefeffe feefeffe
[22:19:51]3ffff750: feefeffe feefeffe feefeffe feefeffe
[22:19:51]3ffff760: feefeffe feefeffe feefeffe feefeffe
[22:19:51]3ffff770: feefeffe feefeffe feefeffe feefeffe
[22:19:51]3ffff780: feefeffe feefeffe feefeffe feefeffe
[22:19:51]3ffff790: feefeffe feefeffe feefeffe feefeffe
[22:19:51]3ffff7a0: feefeffe feefeffe feefeffe feefeffe
[22:19:51]3ffff7b0: feefeffe feefeffe feefeffe feefeffe
[22:19:51]3ffff7c0: feefeffe feefeffe feefeffe feefeffe
[22:19:51]3ffff7d0: feefeffe feefeffe feefeffe feefeffe
[22:19:51]3ffff7e0: feefeffe feefeffe feefeffe feefeffe
[22:19:51]3ffff7f0: feefeffe feefeffe feefeffe feefeffe
[22:19:51]3ffff800: feefeffe feefeffe feefeffe feefeffe
[22:19:51]3ffff810: feefeffe feefeffe feefeffe feefeffe
[22:19:51]3ffff820: feefeffe feefeffe feefeffe feefeffe
[22:19:51]3ffff830: feefeffe feefeffe feefeffe feefeffe
[22:19:51]3ffff840: feefeffe feefeffe feefeffe feefeffe
[22:19:51]3ffff850: feefeffe feefeffe feefeffe feefeffe
[22:19:51]3ffff860: feefeffe feefeffe feefeffe feefeffe
[22:19:51]3ffff870: feefeffe feefeffe feefeffe feefeffe
[22:19:51]3ffff880: feefeffe feefeffe feefeffe feefeffe
[22:19:51]3ffff890: feefeffe feefeffe feefeffe feefeffe
[22:19:51]3ffff8a0: feefeffe feefeffe feefeffe feefeffe
[22:19:51]3ffff8b0: feefeffe feefeffe feefeffe feefeffe
[22:19:51]3ffff8c0: 00000002 00000000 00000020 4010123e
WARNING Decoded 0x4010123e: pp_post [22:19:51]3ffff8d0: 3ffeb312 40104620 3ffeea58 feefeffe
WARNING Decoded 0x40104620: lmacTxFrame [22:19:51]3ffff8e0: 00000001 40103843 3ffef4d8 feefeffe
WARNING Decoded 0x40103843: lmacRecycleMPDU [22:19:51]3ffff8f0: 40103ca6 01a6093e 3ffef770 4010212a
WARNING Decoded 0x40103ca6: lmacRecycleMPDU WARNING Decoded 0x4010212a: trc_NeedRTS [22:19:51]3ffff900: 3ffebb90 00000000 00000000 00000000
[22:19:51]3ffff910: 4010378a 0000002e 7fffffff 00000002
WARNING Decoded 0x4010378a: lmacProcessTxSuccess [22:19:51]3ffff920: 0000012e 3ffebb90 3ffebb90 00000001
[22:19:51]3ffff930: 40102607 00080000 00000010 ffffffff
WARNING Decoded 0x40102607: wDev_ProcessFiq [22:19:51]3ffff940: 00000000 3fffefa0 00002200 4000050c
[22:19:51]3ffff950: 3fffc278 40102304 3fffc200 00000022
WARNING Decoded 0x40102304: wDevProcessFiq [22:19:51]3ffff960: 3ffff970 3ffeb244 00000000 000003e8
[22:19:51]3ffff970: 40206e1d 00000030 00000010 ffffffff
WARNING Decoded 0x40206e1d: esphome::ota::OTAComponent::handle() [22:19:51]3ffff980: 40206e1d 00000000 00000000 3fff0ebc
WARNING Decoded 0x40206e1d: esphome::ota::OTAComponent::handle_() [22:19:51]3ffff990: 00000000 00000000 00000000 fffffffe
[22:19:51]3ffff9a0: ffffffff 3fffc6fc 00000000 3fff2564
[22:19:51]3ffff9b0: 3fff254c 3fff2980 3fff254c 00000030
[22:19:51]3ffff9c0: 00000002 3ffffb34 00000004 fe000032
[22:19:51]3ffff9d0: feefeffe feefeffe feefeffe feefeffe
[22:19:51]3ffff9e0: 00000002 feefeffe feefeffe feefeffe
[22:19:51]3ffff9f0: feefeffe feefeffe 3ffe855c 3ffffa80
[22:19:51]3ffffa00: 3fff2980 3fff254c 4021c708 3fffefa0
WARNING Decoded 0x4021c708: esp_yield [22:19:51]3ffffa10: 3ffeb315 4010463b 000003e8 4021c7ea
WARNING Decoded 0x4010463b: lmacRxDone WARNING Decoded 0x4021c7ea: yield [22:19:51]3ffffa20: 40101f51 3ffeed78 00000018 4021c82d
WARNING Decoded 0x40101f51: trc_NeedRTS WARNING Decoded 0x4021c82d: optimistic_yield [22:19:51]3ffffa30: fffffff4 026aeb40 3fff3864 4020fd6e
WARNING Decoded 0x4020fd6e: WiFiServer::available(unsigned char) [22:19:51]3ffffa40: 3ffebbcc 00000000 00000000 4021924c
WARNING Decoded 0x4021924c: esp8266::MDNSImplementation::MDNSResponder::_writeMDNSServiceDomain(esp8266::MDNSImplementation::MDNSResponder::stcMDNSService const&, bool, bool, esp8266::MDNSImplementation::MDNSResponder::stcMDNSSendParameter&) [22:19:51]3ffffa50: fffffff4 026aeb40 4010256a 00000100
WARNING Decoded 0x4010256a: wDevProcessFiq [22:19:51]3ffffa60: 7fffffff 3fff254c 3fff2564 40206e1d
WARNING Decoded 0x40206e1d: esphome::ota::OTAComponent::handle() [22:19:51]3ffffa70: 00000001 00006208 00000000 00000000
[22:19:51]3ffffa80: 402209a8 00000000 00001388 4000050c
WARNING Decoded 0x402209a8: uart_tx_enabled [22:19:51]3ffffa90: 00000000 00000000 3fffc200 00000022
[22:19:51]3ffffaa0: 3ffebb90 00000000 00000000 00000000
[22:19:51]3ffffab0: 4023ff5d 00000030 00000008 ffffffff
WARNING Decoded 0x4023ff5d: ieee80211_action_vendor_spec_attach [22:19:51]3ffffac0: 402366d7 3fff17dc 3ffeea58 00000000
WARNING Decoded 0x402366d7: ieee80211_crypto_encap [22:19:51]3ffffad0: 0000003d 0000003d 00000008 00000002
[22:19:51]3ffffae0: 3ffee198 00000000 3fff452e 3fff17dc
[22:19:51]3ffffaf0: 3ffeff48 00000000 3fff3934 00000030
[22:19:51]3ffffb00: 00000000 00000000 3ffffe90 40218da2
WARNING Decoded 0x40218da2: esp8266::MDNSImplementation::MDNSResponder::_udpAppend8(unsigned char) [22:19:52]3ffffb10: 00000058 00000000 00000000 00000000
[22:19:52]3ffffb20: 00000000 00000000 3ffffe90 40218ee5
WARNING Decoded 0x40218ee5: esp8266::MDNSImplementation::MDNSResponder::_write8(unsigned char, esp8266::MDNSImplementation::MDNSResponder::stcMDNSSendParameter&) [22:19:52]3ffffb30: 00000000 00000000 3ffffe90 40219201
WARNING Decoded 0x40219201: esp8266::MDNSImplementation::MDNSResponder::_writeMDNSServiceDomain(esp8266::MDNSImplementation::MDNSResponder::stcMDNSService const&, bool, bool, esp8266::MDNSImplementation::MDNSResponder::stcMDNSSendParameter&) [22:19:52]3ffffb40: 00000000 00000000 00000000 00000000
[22:19:52]3ffffb50: 00000000 00000000 00000000 00000000
[22:19:52]3ffffb60: 00000000 00000000 00000000 00000000
[22:19:52]3ffffb70: 00000000 00000000 00000000 00000000
[22:19:52]3ffffb80: 00000000 00000000 00000000 00000000
[22:19:52]3ffffb90: 00000000 00000000 4024787b 00000001
WARNING Decoded 0x4024787b: pp_attach [22:19:52]3ffffba0: ffffffff 00000000 3ffeb461 00000008
[22:19:52]3ffffbb0: 402478ca 3ffeea58 4024787b 00000001
WARNING Decoded 0x402478ca: pp_attach WARNING Decoded 0x4024787b: pp_attach [22:19:52]3ffffbc0: ffffffff 00000000 3ffeb461 00000008
[22:19:52]3ffffbd0: 402478ca 3ffeea58 3fff3934 00000001
WARNING Decoded 0x402478ca: pp_attach [22:19:52]3ffffbe0: 402479d6 3ffeea58 3fff3934 3ffeea58
WARNING Decoded 0x402479d6: pp_attach [22:19:52]3ffffbf0: 00000002 00000000 00000020 4010123e
WARNING Decoded 0x4010123e: pp_post [22:19:52]3ffffc00: 3ffeb312 40246987 3ffeea58 3fff3808
WARNING Decoded 0x40246987: ppTxPkt [22:19:52]3ffffc10: 00000000 40239d37 3ffeff48 3fff1cc4
WARNING Decoded 0x40239d37: ieee80211_output_pbuf [22:19:52]3ffffc20: 00000000 00000002 00000000 3ffeea58
[22:19:52]3ffffc30: 3fff4622 40104cc3 3fff3808 3fff1cc4
WARNING Decoded 0x40104cc3: wdt_feed [22:19:52]3ffffc40: 3fff45ec 00000043 3ffeff48 40218ee5
WARNING Decoded 0x40218ee5: esp8266::MDNSImplementation::MDNSResponder::_write8(unsigned char, esp8266::MDNSImplementation::MDNSResponder::stcMDNSSendParameter&) [22:19:52]3ffffc50: 00000005 4022becd 3fff11b8 3fff1cc4
WARNING Decoded 0x4022becd: glue2esp_linkoutput at /home/gauchard/dev/esp8266/esp8266/tools/sdk/lwip2/builder/glue-esp/lwip-esp.c:748 [22:19:52]3ffffc60: 3fff4622 00000043 3fff44ec 4022c14f
WARNING Decoded 0x4022c14f: new_linkoutput at /home/gauchard/dev/esp8266/esp8266/tools/sdk/lwip2/builder/glue-lwip/lwip-git.c:424 [22:19:52]3ffffc70: 40101f51 3fff1560 3fff44ec 00000000
WARNING Decoded 0x40101f51: trc_NeedRTS [22:19:52]3ffffc80: 3fff1598 3fff1560 3fff45ec 4022c14f
WARNING Decoded 0x4022c14f: new_linkoutput at /home/gauchard/dev/esp8266/esp8266/tools/sdk/lwip2/builder/glue-lwip/lwip-git.c:424 [22:19:52]3ffffc90: 3ffebbc0 3fff1560 3fff45ec 4022c540
WARNING Decoded 0x4022c540: ethernet_output at /home/gauchard/dev/esp8266/esp8266/tools/sdk/lwip2/builder/lwip2-src/src/netif/ethernet.c:312 [22:19:52]3ffffca0: 3fff1448 00000003 3fff1560 40233c48
WARNING Decoded 0x40233c48: etharp_output_to_arp_index at /home/gauchard/dev/esp8266/esp8266/tools/sdk/lwip2/builder/lwip2-src/src/core/ipv4/etharp.c:770 [22:19:52]3ffffcb0: 7fffffff 3ffebbc0 3ffebbc0 3fff144c
[22:19:52]3ffffcc0: 3fff1598 00000003 3fff1560 40233c48
WARNING Decoded 0x40233c48: etharp_output_to_arp_index at /home/gauchard/dev/esp8266/esp8266/tools/sdk/lwip2/builder/lwip2-src/src/core/ipv4/etharp.c:770 [22:19:52]3ffffcd0: 3ffffd00 026ae994 00002200 3fff44ec
[22:19:52]3ffffce0: 00000002 3fff45ec 3fff1560 40233e9c
WARNING Decoded 0x40233e9c: etharp_output_LWIP2 at /home/gauchard/dev/esp8266/esp8266/tools/sdk/lwip2/builder/lwip2-src/src/core/ipv4/etharp.c:885 [22:19:52]3ffffcf0: 3ffe0000 00000000 00000010 3fff45ec
[22:19:52]3ffffd00: 3fff45ec 3fff3608 3fff1560 40233e9c
WARNING Decoded 0x40233e9c: etharp_output_LWIP2 at /home/gauchard/dev/esp8266/esp8266/tools/sdk/lwip2/builder/lwip2-src/src/core/ipv4/etharp.c:885 [22:19:52]3ffffd10: 3fff0000 00000000 00000010 4021e0f0
WARNING Decoded 0x4021e0f0: malloc [22:19:52]3ffffd20: 3fff4630 3fff3608 3fff1560 402349b4
WARNING Decoded 0x402349b4: ip4_output_if_opt_src at /home/gauchard/dev/esp8266/esp8266/tools/sdk/lwip2/builder/lwip2-src/src/core/ipv4/ip4.c:1007 [22:19:52]3ffffd30: 0000005a 00000218 00000284 4021e0f0
WARNING Decoded 0x4021e0f0: malloc [22:19:52]3ffffd40: 00000014 3fff3604 000000ff 00000000
[22:19:52]3ffffd50: 00000006 3fff1520 00000000 4022c5d0
WARNING Decoded 0x4022c5d0: memp_malloc at /home/gauchard/dev/esp8266/esp8266/tools/sdk/lwip2/builder/lwip2-src/src/core/memp.c:176 [22:19:52]3ffffd60: 3fff1560 00000000 00000000 3fff3604
[22:19:52]3ffffd70: 3fff3dcc 3fff1560 00000000 402349fc
WARNING Decoded 0x402349fc: ip4_output_if_opt at /home/gauchard/dev/esp8266/esp8266/tools/sdk/lwip2/builder/lwip2-src/src/core/ipv4/ip4.c:820 [22:19:52]3ffffd80: 3fff1560 00000000 00000000 00000218
[22:19:52]3ffffd90: 00000000 00000000 3fff3604 40234a22
WARNING Decoded 0x40234a22: ip4_output_if at /home/gauchard/dev/esp8266/esp8266/tools/sdk/lwip2/builder/lwip2-src/src/core/ipv4/ip4.c:793 [22:19:52]3ffffda0: 00000000 4bc6a7f0 81cac083 00000000
[22:19:52]3ffffdb0: 00000000 00000000 4bc6a7f0 00000000
[22:19:52]3ffffdc0: 3fff1560 0000000a 40100508 0000a382
WARNING Decoded 0x40100508: millis [22:19:53]3ffffdd0: 3fff1178 00000412 00000412 4021deb0
WARNING Decoded 0x4021deb0: _umm_free at umm_malloc.cpp [22:19:53]3ffffde0: 3fff40a0 3fff4094 00000001 4021e2d4
WARNING Decoded 0x4021e2d4: free [22:19:53]3ffffdf0: 3fff414c 00000000 00000019 4022b3e6
WARNING Decoded 0x4022b3e6: operator delete(void) at /workdir/arena/gcc/xtensa-lx106-elf/libstdc++-v3-nox/libsupc++/../../../../../dl/gcc-xtensa/libstdc++-v3/libsupc++/del_op.cc:48 [22:19:53]3ffffe00: 3fff40a0 00000000 3fff3fac 4020143a
WARNING Decoded 0x4020143a: _ZNSt12_Vector_baseIhSaIhEE13_M_deallocateEPhj$isra$44 at api_connection.cpp [22:19:53]3ffffe10: 3fff40a0 3fff4094 00000000 40202030
WARNING Decoded 0x40202030: esphome::api::APIConnection::send_buffer(esphome::api::ProtoWriteBuffer, unsigned int) [22:19:53]3ffffe20: 3fff3704 3fff3707 3fff3708 3fff1940
[22:19:53]3ffffe30: 00000019 00000000 3ffffe70 40203fb8
WARNING Decoded 0x40203fb8: esphome::api::SensorStateResponse::encode(esphome::api::ProtoWriteBuffer) const [22:19:53]3ffffe40: 3fff40a0 3ffffe84 00000028 3fff1940
[22:19:53]3ffffe50: 00000000 4bc6a7f0 c8b43958 00000000
[22:19:53]3ffffe60: 00000000 00000000 4bc6a7f0 00000000
[22:19:53]3ffffe70: 402200f8 52658f89 40100508 0000a382
WARNING Decoded 0x402200f8: uart_tx_enabled WARNING Decoded 0x40100508: millis [22:19:53]3ffffe80: 00000000 3fff2ce4 3fff193c 402046cc
WARNING Decoded 0x402046cc: esphome::api::APIServer::on_sensor_update(esphome::sensor::Sensor*, float) [22:19:53]3ffffe90: 00bd0f38 3fff27a4 00000000 00000002
[22:19:53]3ffffea0: 00000000 4bc6a7f0 3b645a1c 00000000
[22:19:53]3ffffeb0: 00000000 00000000 4bc6a7f0 00000000
[22:19:53]3ffffec0: 00000000 4bc6a7f0 4ccccccc 00000000
[22:19:53]3ffffed0: 00000000 00000000 4bc6a7f0 00000000
[22:19:53]3ffffee0: 00000000 4bc6a7f0 40100508 0000a383
WARNING Decoded 0x40100508: millis [22:19:53]3ffffef0: 40104dd2 00c77ebc 472b020c 00000000
WARNING Decoded 0x40104dd2: wdt_feed [22:19:53]3fffff00: 40104e8e 3fff05a8 00c77ebc 00000000
WARNING Decoded 0x40104e8e: ets_timer_setfn [22:19:53]3fffff10: 40105060 00c77ebc 3fff1114 00000000
WARNING Decoded 0x40105060: ets_timer_arm_new [22:19:53]3fffff20: 3fff05d0 3fff1114 3ffe855c 3fff1114
[22:19:53]3fffff30: 0000a383 3fff26fc 4021c708 3fffefa0
WARNING Decoded 0x4021c708: esp_yield [22:19:53]3fffff40: 3fff1114 0000a383 00000008 4021cce7
WARNING Decoded 0x4021cce7: delay [22:19:53]3fffff50: 3fff298c 00000010 3fff0d1c 4020a794
WARNING Decoded 0x4020a794: esphome::Application::loop() [22:19:53]3fffff60: 3fff2901 00000000 3fff0d8c 3fff0db0
[22:19:53]3fffff70: 3fff298c 3ffffee8 3fff0d94 00000000
[22:19:53]3fffff80: 00000090 feefeffe feefeffe 3fff10b8
[22:19:53]3fffff90: 3fffdad0 00000000 3fff1088 4020d338
WARNING Decoded 0x4020d338: loop [22:19:53]3fffffa0: 3fffdad0 00000000 3fff1088 4021c7b8
WARNING Decoded 0x4021c7b8: loop_wrapper() at core_esp8266_main.cpp [22:19:53]<<<stack<<<

When I hook up just to another port I get Cause 4 resets, but much less data. I have changed the logging level and that seems to help lessen the number of crashes a little, but still happening pretty often.

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

wdt reset load 0x4010f000, len 1384, room 16 tail 8 chksum 0x2d csum 0x2d v8b899c12 ~ld

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

wdt reset load 0x4010f000, len 1384, room 16 tail 8 chksum 0x2d csum 0x2d v8b899c12 ~ld

jazzmonger commented 4 years ago

SOLVED (I think***)

Ok, this is an esoteric 802.11 wifi related issue. I'm not sure if it's at the esp chip level, the firmware chip driver for the WiFi chip on the esp or the esphome code our brilliant young programmer created for these cool little esp8266 based chips.

Here's the workaround:

On the Access Point that all your esp's are connecting to (or all your AP's) change the channel's bandwidth to be FIXED at 20mhz, not AUTO. Restart you AP. I use channel 8, but I have not verified that other channels work or don't work yet. I will do more testing to try and isolate the issue further after I post this.

The esps connection algorithm might be using a 40mhz channel width as its default connection scheme (I think- not verified) and this might be where the problem lies. I'll have to dig into the WiFi section of the 8266 chip specs to more fully understand what's going on.

I've tried this on the AP both nodemcu AND D1 mini's connect to and it "fixes" the problem. Unfortunately some APs have buggy 802.11 standards implemented in them (cheap Chinese crap often comes with brain dead programmers trying to implement complex standards) and try as I might, the $500 Hawking HOW17ACM I just bought is absolute garbage. The esps as well as other devices like a Mac and an iPad also have problems on this AP.

My trusty Ubiquity Loco M2's are rock solid. But, the esps need the 20mhz channel bandwidth setting to stay connected. Otherwise they cycle connect/disconnect.

If some of you can try this fix and report back here, this might give the ESPHOME devs some clues about where to look. There is some documented WiFi weirdness specifically regarding WiFi channel width on these devices when they are underclocked, so this might be a place to look:

https://hackaday.com/2019/01/04/underclocking-the-esp8266-leads-to-wifi-weirdness/

Jeff

Firemogle commented 4 years ago

I wanted to update that I recently started having these issues and the wifi fix did not work for me.

MaximumSU commented 4 years ago

On the Access Point that all your esp's are connecting to (or all your AP's) change the channel's bandwidth to be FIXED at 20mhz, not AUTO.

I not confirm this also. I tried to fix the WiFi channel width to 20MHz - no difference. I have 3 types of routers (4 pieces total). All routers are dual band (2.4GHz, and 5GHz), 3 routers of 2 types (2 kinds of Zyxel) connected as routed MESH network and last one of another type (TPLINK) stay alone. Also i have 2 types of boards with ESP8266 and ESP8285 chips.

  1. I use for almost all devices the 1.13.6 firmware with Arduino dev. For this firmware, if it connected to the one router - no disconnects at all. But, if same device connected occasenly to another router within the MESH network (to another type of router) - it starting disconnect constantly.
  2. For the one device i use 1.15 dev and Arduino dev. This device connected to the 3-rd type of router outside the MESH network, and it also no disconnects at all. BUT - if same device connected to the one of ANY routers from MESH network - it start's disconnecting constantly. If this device is flashed by 1.13.6 firmware - the same as punkt 1. So, i can't find any pattern in such behavior. But it is not random also. If it not works reliable in some combination of firmware+router - it never works, if some combination works - it always works.

PS:All devices have IP's manualy set.

daffster commented 4 years ago

I've applied the 20MHz channel change, and am still having these issues across multiple devices. Wemos D1 Mini, ESP-12E bare, NodeMCU and bulbs based on ESP8285 are all disconnecting.

This only seems to impact devices that utilise esp8266_pwm though.

laca75tn commented 4 years ago

I am having the same issues. Node MCUs using esp8266_pwm. I have three blinds with servos. They have been working perfectly for months, and in the last week or two they started having erratic behavior. WiFi signal strength is around 60, as I said they were working flawlessly for months and now all three are disconnecting several times each hour.

mikalauskas commented 4 years ago

it's not related wifi issue on router side for sure, i have the same problem but can still ping esp, also my wifi is 20mhz on default. and esphome ... logs monitoring disconnects but pings.

my dirty workaround is to restart esp every day early morning

time:
  - platform: sntp
    id: sntp_time
    on_time:
      - seconds: 0
        minutes: 30
        hours: 4
        then:
          - switch.turn_on: restart1

switch:
  - platform: restart
    name: "rf_bridge restart"
    id: restart1
    internal: true
back0nline commented 3 years ago

I'm also having this problem for months.

At first i taught that was related with the Aruba AP Firmware but after 3 updates nothing has changed. What i know is that all my esp loses the connection with the AP, and if i restart the AP all esp are able to reconnect again. It's nothing related with the AP because all my other devices are working fine only my esphome devices are not able work.

On the AP I forced all my connected devices to reconnect after 24h and it did not solve the issue.

daffster commented 3 years ago

My initial comment states that this only impacted devices with esp8266_pwm, but I was wrong.

garage-esphome.log I've attached a log of a one of my esp8266 wemos d1 mini's that shows the full startup, a crash and a reboot.

CarlosGS commented 3 years ago

image

I observe instantaneous re-connections (see gray strips), exclusively when enabling power_save_mode: light.

Note #1532 power save modes light/high are swapped at the moment. Reconnections don't happen with modem sleep (currently wrong as ""high"" mode) See that thread for more info.

CarlosGS commented 3 years ago

In any case, would it be possible to have some tolerance against re-connections? A couple of seconds of allowance seem more reasonable rather than logging every instantaneous drop.

glenn20 commented 3 years ago

After belatedly finding the sollution from @jazzmonger (set wifi chanel width to 20MHz - not 40MHz), I confirm this instantly fixed my connection issues with all my ESP32 esphome devices. Before the change, all my esphome sensors toggled to "unavailable" every minute or two for a few seconds up to a minute or so. This has plagued and frustrated me for months.

My access point is a TP-Link Archer C2600 running openwrt 19.04.

The difference is easily diagnosed with "ping":

wifi channel width set to 40MHz:

PING downstairs.lan (192.168.40.10): 56 data bytes 64 bytes from 192.168.40.10: seq=3 ttl=255 time=4212.492 ms 64 bytes from 192.168.40.10: seq=4 ttl=255 time=3214.421 ms 64 bytes from 192.168.40.10: seq=5 ttl=255 time=2215.980 ms 64 bytes from 192.168.40.10: seq=6 ttl=255 time=1217.056 ms 64 bytes from 192.168.40.10: seq=7 ttl=255 time=216.737 ms 64 bytes from 192.168.40.10: seq=11 ttl=255 time=4178.138 ms 64 bytes from 192.168.40.10: seq=12 ttl=255 time=3177.930 ms 64 bytes from 192.168.40.10: seq=13 ttl=255 time=2179.113 ms 64 bytes from 192.168.40.10: seq=14 ttl=255 time=1179.397 ms 64 bytes from 192.168.40.10: seq=15 ttl=255 time=179.243 ms 64 bytes from 192.168.40.10: seq=17 ttl=255 time=4570.686 ms 64 bytes from 192.168.40.10: seq=18 ttl=255 time=3571.966 ms 64 bytes from 192.168.40.10: seq=19 ttl=255 time=2572.417 ms 64 bytes from 192.168.40.10: seq=20 ttl=255 time=1573.015 ms 64 bytes from 192.168.40.10: seq=21 ttl=255 time=574.027 ms

wifi channel set to 20MHz:

PING downstairs.lan (192.168.40.10): 56 data bytes 64 bytes from 192.168.40.10: seq=0 ttl=255 time=194.041 ms 64 bytes from 192.168.40.10: seq=1 ttl=255 time=218.359 ms 64 bytes from 192.168.40.10: seq=2 ttl=255 time=36.877 ms 64 bytes from 192.168.40.10: seq=3 ttl=255 time=59.691 ms 64 bytes from 192.168.40.10: seq=4 ttl=255 time=83.883 ms 64 bytes from 192.168.40.10: seq=5 ttl=255 time=108.578 ms 64 bytes from 192.168.40.10: seq=6 ttl=255 time=128.894 ms 64 bytes from 192.168.40.10: seq=7 ttl=255 time=153.089 ms 64 bytes from 192.168.40.10: seq=8 ttl=255 time=174.360 ms 64 bytes from 192.168.40.10: seq=9 ttl=255 time=197.648 ms 64 bytes from 192.168.40.10: seq=10 ttl=255 time=16.564 ms 64 bytes from 192.168.40.10: seq=11 ttl=255 time=40.342 ms 64 bytes from 192.168.40.10: seq=12 ttl=255 time=65.580 ms 64 bytes from 192.168.40.10: seq=13 ttl=255 time=88.499 ms 64 bytes from 192.168.40.10: seq=14 ttl=255 time=118.784 ms 64 bytes from 192.168.40.10: seq=15 ttl=255 time=138.178 ms 64 bytes from 192.168.40.10: seq=16 ttl=255 time=156.239 ms 64 bytes from 192.168.40.10: seq=17 ttl=255 time=240.215 ms 64 bytes from 192.168.40.10: seq=18 ttl=255 time=202.772 ms

glenn20 commented 3 years ago

After belatedly finding the sollution from @jazzmonger (set wifi chanel width to 20MHz - not 40MHz), I confirm this instantly fixed my connection issues with all my ESP32 esphome devices. Before the change, all my esphome sensors toggled to "unavailable" every minute or two for a few seconds up to a minute or so. This has plagued and frustrated me for months.

Ok - I got ahead of myself. Turns out it is not changing the channel width to 20MHz, it seems to just be restarting the interface which resolves the issue - for a time - but it returns after a few minutes up to over 10 minutes ( I just got a good stretch of 10 mins of perfection when I first tried it). So it goes...

stale[bot] commented 3 years ago

This issue has been automatically marked as stale because it has not had recent activity. It will be closed if no further activity occurs. Thank you for your contributions.

chemelli74 commented 3 years ago

Seems the "solution" provided is just a workaround. Any better and reliable approach ?

Simone

XavitoHA19 commented 3 years ago

On your router make sure that you are using 20MHz width and a fixed channel. Upgrade to the latest version of EspHome. That fixed all my issues

chemelli74 commented 3 years ago

On your router make sure that you are using 20MHz width and a fixed channel. Upgrade to the latest version of EspHome. That fixed all my issues

I got it that it fixed issues for you, but is more a workaround than a real solution ;-)

back0nline commented 3 years ago

I dont have the 20mhz enable and i dont have any problems for months but I don't know what solved it

jmvaz commented 2 years ago

20mhz doesn't solve the problem. tested with ubiquiti amplifi mesh system, with sonoffs, d1 mini pro, blitzwolf shp6, and so on. this issue is really a showstopper for me. :(

glenn20 commented 2 years ago

Hopefully this will be useful to people. After over a year of continuous disconnects/reconnects and false dawns with various work-arounds I have had no problems with disconnects for over 8 months now.

I found the combination of two changes fixed this for me:

  1. Set arduino_version: latest under the esphome: section.
  2. Set power_save_mode: none under the wifi: section.

I also set a static IP address, but I don't believe that has any effect.

It may well be that the first fix is no longer necessary, but I haven't backed it out yet to test that.

Good luck.

chrispontiga commented 2 years ago

@glenn20 Thank you. You saved my life. Was almost done installing some monochromatic lights under my cabinets and in the process of buttoning things up. Then noticed they were not responding properly to HA controls timely, and pings were super slow and all over the map. They were just working earlier. Tore through the project box again and troubleshooted all the soldered connections. Was ready to just build another one when I found your suggestion. Wasted so much time with that stupid power save mode. I had updated to the latest ESPHome release 2022.1.1 which enabled this setting by default, just before installing under my cabinets. Once I fixed that setting, it was all good and working. Wasted an extra three hours because of this stupid setting.

theres commented 2 years ago

For me only one thing has helped - change of AP. In my case it was change to UniFi, but I've heard a lot good about Mikrotik as well. Before: restart every 5-20min After: uptime 2 weeks (since I've change the AP)

CarlosGS commented 2 years ago

Wasted so much time with that stupid power save mode. I had updated to the latest ESPHome release 2022.1.1 which enabled this setting by default

@chrispontiga The defaults have not been changed, though. They are "modem sleep" for ESP32 and "none" for ESP8266.

Don't blame power_save_mode. Note in ESP8266 it is simply mislabeled! People using power_save_mode: light are in fact using the highest setting which throttles the CPU and causes problems. Afterwards, they don't bother trying the "high" setting. But in fact the best setting to use in ESP8266 is power_save_mode: high, see https://github.com/esphome/issues/issues/1532#issuecomment-718669014 for details & measurements. That setting matches the ESP32 default (modem sleep) which really reduces heating.

If you also set arduino_version: latest, then that's what fixed the problem and is a good clue to find the cause :smile:

chrispontiga commented 2 years ago

@CarlosGS Currently on an ESP32. The only thing that was added to resolve the connectivity/responsiveness issue was power power_save_mode: none. I did not add the arduino_version line. This is why I blame the power_save_mode.

It worked fine before the update to 2022.1.1. It was hardly working after the update.

atv2016 commented 2 years ago

I tried to set arduino_version but if i set it under esp_home it says move it to the board specific section. When i add it there it doesn't seem to like it either.

Does anyone have any code examples with latest ESPhome where to put this statement?

ADeadPixel commented 2 years ago

I tried to set arduino_version but if i set it under esp_home it says move it to the board specific section. When i add it there it doesn't seem to like it either.

Does anyone have any code examples with latest ESPhome where to put this statement?

I'm experiencing the same issue. I cannot set the Ardunio_Version in ESPHome 2022.6.2. I'm attempting the WiFi power mode change right now to see if this fixes my devices that keep disconnecting.

EDIT: Updating the power mode appears to have fixed my issues.

MikeMakes commented 2 years ago

I found the same problem and I observed that the esp8266 nodemcu v1 would reconnect by his own on certain terms. In my case it would disconnect after 5min exactly and never come back unless; -Reset the esp -Reset the AP -Some action occurred in the esp (for example, a toggle in a gpio switch scripted at some after a delay) Because of the later I supossed it was some kind of power saving mode so I include power_save_mode: none and it got fixed. Looking for more info (to see if this was intentional) I ended up here. I will try to change the AP config and itself to see if it changes anything without changing the power save mode.

EDIT: Those things improved the random disconnects and made the esp reconnect more often but changing the AP was definitive. Fixed at 20Mhz just in case and everything ok for now.