esphome / issues

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

esp32 is unable to get values from HA sensors or upload sensor values to HA if a display component (ssd1327_i2c) is used #1874

Closed bea30 closed 3 years ago

bea30 commented 3 years ago

Operating environment/Installation (Hass.io/Docker/pip/etc.):

HassOS with esphome plugin ESP (ESP32/ESP8266, Board/Sonoff):

ESP32 link: https://www.ebay.co.uk/itm/ESP32-Development-Board-ESP-32-38-pin-DevKitC-Layout-UK-Seller-/322484359954 ESPHome version (latest production, beta, dev branch)

ESPHome version 1.16.2 Affected component:

https://esphome.io/components/display/ssd1327.html https://esphome.io/components/binary_sensor/homeassistant.html

Description of problem: When a display component is defined and used over i2c all attached sensors on the board stops to uploading their states to HA and their status in HA is unavailable. (MHZ19, DHT22, BME680, MQ2 sensors) No matter if display only shows static text or if display is showing their values. Display component is able to show values of sensors without any problem.

If a display component is commented or removed all states are uploaded to HA without any problem.

I am using following display marked as gme128128-02 v3.1 with 4 pins - GNC, VCC, SCL,SDA https://www.aliexpress.com/item/1005001462777917.html?spm=a2g0o.productlist.0.0.3b8427dd7eZSAs&algo_pvid=337d1302-6f6b-4b02-9f3e-1a6db797d95b&algo_expid=337d1302-6f6b-4b02-9f3e-1a6db797d95b-4&btsid=0bb0623616142669305485604e3fb9&ws_ab_test=searchweb0_0,searchweb201602_,searchweb201603_

Display connection: SDA -> 21 SCL -> 22 VCC -> 3v3 GND -> GND

or

SDA -> 21 SCL -> 22 VCC -> 5V GND -> GND

Problem-relevant YAML-configuration entries:

esphome:
  name: test_esp32
  platform: ESP32
  board: esp32doit-devkit-v1

wifi:
  ssid: "xxxx"
  password: "xxxx"

  # Enable fallback hotspot (captive portal) in case wifi connection fails
  ap:
    ssid: "Test Esp32 Fallback Hotspot"
    password: "xxxx"

captive_portal:

# Enable logging
logger:
  level: VERY_VERBOSE
  logs:
    i2c: DEBUG

# Enable Home Assistant API
api:
  password: "xxxx"

ota:
  password: "xxxx"

# i2c - displej
i2c:
 - id: bus_a
   sda: 21
   scl: 22 
   scan: True

sensor:
  - platform: homeassistant
    id: temp
    entity_id: sensor.spalna_bme_teplota
    internal: true

  - platform: homeassistant
    id: humidity
    entity_id: sensor.spalna_bme_vlhkost
    internal: true

  - platform: homeassistant
    id: co2
    entity_id: sensor.mh_z19_co2
    internal: true

font:
  - file: "fonts/ComicSansMS3.ttf"
    id: my_font
    size: 20
  - file: "fonts/ComicSansMS3.ttf"
    id: small
    size: 18

display:
  - platform: ssd1327_i2c
    i2c_id: bus_a
    model: "SSD1327 128x128"
    #reset_pin: 
    address: 0x3C
    lambda: |-
      if (id(temp).has_state()) {
        it.printf(0, 0, id(my_font), "T: %.1fC°", id(temp).state);
      }
      if (id(humidity).has_state()) {
        it.printf(0, 21, id(my_font), "H: %.1f %", id(humidity).state);
      }
      if (id(co2).has_state()) {
        it.printf(0, 41, id(my_font), "CO: %.0f ppm", id(co2).state);
      }

Logs (if applicable):

INFO Reading configuration /config/esphome/test_esp32.yaml...
INFO Starting log output from test_esp32.local using esphome API
INFO Connecting to test_esp32.local:6053 (192.xxx.xxx.154)
INFO Successfully connected to test_esp32.local
[15:56:03][V][app:081]: A component took a long time in a loop() cycle (1.84 s).
[15:56:03][V][app:082]: Components should block for at most 20-30ms in loop().
[15:56:03][I][app:105]: ESPHome version 1.16.2 compiled on Feb 25 2021, 15:02:00
[15:56:03][C][wifi:443]: WiFi:
[15:56:03][C][wifi:303]:   SSID: [redacted]
[15:56:03][C][wifi:304]:   IP Address: 192.xxx.xxx.154
[15:56:03][C][wifi:306]:   BSSID: [redacted]
[15:56:03][C][wifi:307]:   Hostname: 'test_esp32'
[15:56:03][C][wifi:311]:   Signal strength: -43 dB ▂▄▆█
[15:56:03][V][wifi:313]:   Priority: 0.0
[15:56:03][C][wifi:315]:   Channel: 4
[15:56:03][C][wifi:316]:   Subnet: 255.255.255.0
[15:56:03][C][wifi:317]:   Gateway: 192.xxx.xxx.1
[15:56:03][C][wifi:318]:   DNS1: 192.xxx.xxx.1
[15:56:03][C][wifi:319]:   DNS2: 8.8.8.8
[15:56:03][VV][scheduler:152]: Running interval 'update' with interval=1000 last_execution=98574 (now=100752)
[15:56:05][D][api:067]: Disconnecting Home Assistant 2021.2.3 (192.xxx.xxx.100)
[15:56:05][VV][api.service:192]: on_hello_request: HelloRequest {
  client_info: 'Home Assistant 2021.2.3'
}
[15:56:05][V][api.connection:588]: Hello from client: 'Home Assistant 2021.2.3 (192.xxx.xxx.100)'
[15:56:05][VV][api.service:012]: send_hello_response: HelloResponse {
  api_version_major: 1
  api_version_minor: 3
  server_info: 'test_esp32 (esphome v1.16.2)'
}
[15:56:05][VV][api.service:220]: on_ping_request: PingRequest {}
[15:56:05][VV][api.service:032]: send_ping_response: PingResponse {}
[15:56:05][V][app:081]: A component took a long time in a loop() cycle (1.87 s).
[15:56:05][V][app:082]: Components should block for at most 20-30ms in loop().
[15:56:05][C][i2c:028]: I2C Bus:
[15:56:05][C][i2c:029]:   SDA Pin: GPIO21
[15:56:05][C][i2c:030]:   SCL Pin: GPIO22
[15:56:05][C][i2c:031]:   Frequency: 50000 Hz
[15:56:05][I][i2c:033]: Scanning i2c bus for active devices...
[15:56:05][I][i2c:040]: Found i2c device at address 0x3C
[15:56:05][VV][scheduler:152]: Running interval 'update' with interval=1000 last_execution=100574 (now=102785)
[15:56:07][VV][api.service:199]: on_connect_request: ConnectRequest {
  password: 'xxxxx'
}
[15:56:07][D][api.connection:604]: Client 'Home Assistant 2021.2.3 (192.xxx.xxx.100)' connected successfully!
[15:56:07][VV][api.service:016]: send_connect_response: ConnectResponse {
  invalid_password: NO
}
[15:56:07][V][app:081]: A component took a long time in a loop() cycle (1.83 s).
[15:56:07][V][app:082]: Components should block for at most 20-30ms in loop().
[15:56:07][C][logger:185]: Logger:
[15:56:07][C][logger:186]:   Level: VERY_VERBOSE
[15:56:07][C][logger:187]:   Log Baud Rate: 115200
[15:56:07][C][logger:188]:   Hardware UART: UART0
[15:56:07][C][logger:190]:   Level for 'i2c': DEBUG
[15:56:07][VV][scheduler:152]: Running interval 'update' with interval=1000 last_execution=102574 (now=104653)
[15:56:09][VV][api.service:234]: on_device_info_request: DeviceInfoRequest {}
[15:56:09][VV][api.service:036]: send_device_info_response: DeviceInfoResponse {
  uses_password: YES
  name: 'test_esp32'
  mac_address: '3C:61:05:2F:D3:FC'
  esphome_version: '1.16.2'
  compilation_time: 'Feb 25 2021, 15:02:00'
  model: 'DOIT ESP32 DEVKIT V1'
  has_deep_sleep: NO
}
[15:56:09][V][app:081]: A component took a long time in a loop() cycle (1.85 s).
[15:56:09][V][app:082]: Components should block for at most 20-30ms in loop().
[15:56:09][C][ssd1327_i2c:023]: I2C SSD1327
[15:56:09][C][ssd1327_i2c:023]:   Rotations: 0 °
[15:56:09][C][ssd1327_i2c:023]:   Dimensions: 128px x 128px
[15:56:09][C][ssd1327_i2c:024]:   Address: 0x3C
[15:56:09][C][ssd1327_i2c:025]:   Model: SSD1327 128x128
[15:56:09][C][ssd1327_i2c:027]:   Update Interval: 1.0s
[15:56:09][VV][scheduler:152]: Running interval 'update' with interval=1000 last_execution=104574 (now=106543)
[15:56:11][VV][api.service:241]: on_list_entities_request: ListEntitiesRequest {}
[15:56:11][VV][api.service:220]: on_ping_request: PingRequest {}
[15:56:11][VV][api.service:032]: send_ping_response: PingResponse {}
[15:56:11][V][app:081]: A component took a long time in a loop() cycle (1.83 s).
[15:56:11][V][app:082]: Components should block for at most 20-30ms in loop().
[15:56:11][VV][scheduler:152]: Running interval 'update' with interval=1000 last_execution=105574 (now=108395)
[15:56:13][V][app:081]: A component took a long time in a loop() cycle (1.82 s).
[15:56:13][V][app:082]: Components should block for at most 20-30ms in loop().
[15:56:13][C][captive_portal:169]: Captive Portal:
[15:56:13][VV][scheduler:152]: Running interval 'update' with interval=1000 last_execution=107574 (now=110235)
[15:56:15][VV][api.service:206]: on_disconnect_request: DisconnectRequest {}
[15:56:15][VV][api.service:024]: send_disconnect_response: DisconnectResponse {}
[15:56:15][V][app:081]: A component took a long time in a loop() cycle (1.83 s).
[15:56:15][V][app:082]: Components should block for at most 20-30ms in loop().
[15:56:15][C][ota:029]: Over-The-Air Updates:
[15:56:15][C][ota:030]:   Address: test_esp32.local:3232
[15:56:15][C][ota:032]:   Using Password.
[15:56:15][VV][scheduler:152]: Running interval 'update' with interval=1000 last_execution=109574 (now=112099)
[15:56:17][D][api:067]: Disconnecting Home Assistant 2021.2.3 (192.xxx.xxx.100)
[15:56:17][VV][api.service:192]: on_hello_request: HelloRequest {
  client_info: 'Home Assistant 2021.2.3'
}
[15:56:17][V][api.connection:588]: Hello from client: 'Home Assistant 2021.2.3 (192.xxx.xxx.100)'
[15:56:17][VV][api.service:012]: send_hello_response: HelloResponse {
  api_version_major: 1
  api_version_minor: 3
  server_info: 'test_esp32 (esphome v1.16.2)'
}
[15:56:17][VV][api.service:220]: on_ping_request: PingRequest {}
[15:56:17][VV][api.service:032]: send_ping_response: PingResponse {}
[15:56:17][V][app:081]: A component took a long time in a loop() cycle (1.87 s).
[15:56:17][V][app:082]: Components should block for at most 20-30ms in loop().
[15:56:17][C][api:095]: API Server:
[15:56:17][C][api:096]:   Address: test_esp32.local:6053
[15:56:17][VV][scheduler:152]: Running interval 'update' with interval=1000 last_execution=111574 (now=113987)
[15:56:18][VV][api.service:199]: on_connect_request: ConnectRequest {
  password: 'xxxx'
}
[15:56:18][D][api.connection:604]: Client 'Home Assistant 2021.2.3 (192.xxx.xxx.100)' connected successfully!
[15:56:18][VV][api.service:016]: send_connect_response: ConnectResponse {
  invalid_password: NO
}
[15:56:18][V][app:081]: A component took a long time in a loop() cycle (1.84 s).
[15:56:19][V][app:082]: Components should block for at most 20-30ms in loop().
[15:56:19][C][homeassistant.sensor:024]: Homeassistant Sensor 'temp'
[15:56:19][C][homeassistant.sensor:024]:   Unit of Measurement: ''
[15:56:19][C][homeassistant.sensor:024]:   Accuracy Decimals: 1
[15:56:19][C][homeassistant.sensor:025]:   Entity ID: 'sensor.spalna_bme_teplota'
[15:56:19][VV][scheduler:152]: Running interval 'update' with interval=1000 last_execution=113574 (now=115869)
[15:56:20][VV][api.service:234]: on_device_info_request: DeviceInfoRequest {}
[15:56:20][VV][api.service:036]: send_device_info_response: DeviceInfoResponse {
  uses_password: YES
  name: 'test_esp32'
  mac_address: '3C:61:05:2F:D3:FC'
  esphome_version: '1.16.2'
  compilation_time: 'Feb 25 2021, 15:02:00'
  model: 'DOIT ESP32 DEVKIT V1'
  has_deep_sleep: NO
}
[15:56:20][V][app:081]: A component took a long time in a loop() cycle (1.85 s).
[15:56:20][V][app:082]: Components should block for at most 20-30ms in loop().
[15:56:20][C][homeassistant.sensor:024]: Homeassistant Sensor 'humidity'
[15:56:20][C][homeassistant.sensor:024]:   Unit of Measurement: ''
[15:56:20][C][homeassistant.sensor:024]:   Accuracy Decimals: 1
[15:56:20][C][homeassistant.sensor:025]:   Entity ID: 'sensor.spalna_bme_vlhkost'
[15:56:20][VV][scheduler:152]: Running interval 'update' with interval=1000 last_execution=115574 (now=117752)
[15:56:22][VV][api.service:241]: on_list_entities_request: ListEntitiesRequest {}
[15:56:22][VV][api.service:220]: on_ping_request: PingRequest {}
[15:56:22][VV][api.service:032]: send_ping_response: PingResponse {}
[15:56:22][V][app:081]: A component took a long time in a loop() cycle (1.83 s).
[15:56:22][V][app:082]: Components should block for at most 20-30ms in loop().
[15:56:22][C][homeassistant.sensor:024]: Homeassistant Sensor 'co2'
[15:56:22][C][homeassistant.sensor:024]:   Unit of Measurement: ''
[15:56:22][C][homeassistant.sensor:024]:   Accuracy Decimals: 1
[15:56:22][C][homeassistant.sensor:025]:   Entity ID: 'sensor.mh_z19_co2'
[15:56:22][VV][scheduler:152]: Running interval 'update' with interval=1000 last_execution=117574 (now=119623)
[15:56:24][V][app:081]: A component took a long time in a loop() cycle (1.82 s).
[15:56:24][V][app:082]: Components should block for at most 20-30ms in loop().
[15:56:24][VV][scheduler:152]: Running interval 'update' with interval=1000 last_execution=119574 (now=121458)
[15:56:26][VV][api.service:206]: on_disconnect_request: DisconnectRequest {}
[15:56:26][VV][api.service:024]: send_disconnect_response: DisconnectResponse {}
[15:56:26][V][app:081]: A component took a long time in a loop() cycle (1.83 s).
[15:56:26][V][app:082]: Components should block for at most 20-30ms in loop().
[15:56:26][VV][scheduler:152]: Running interval 'update' with interval=1000 last_execution=120574 (now=123310)
[15:56:28][D][api:067]: Disconnecting Home Assistant 2021.2.3 (192.xxx.xxx.100)
[15:56:28][VV][api.service:192]: on_hello_request: HelloRequest {
  client_info: 'Home Assistant 2021.2.3'
}
[15:56:28][V][api.connection:588]: Hello from client: 'Home Assistant 2021.2.3 (192.xxx.xxx.100)'
[15:56:28][VV][api.service:012]: send_hello_response: HelloResponse {
  api_version_major: 1
  api_version_minor: 3
  server_info: 'test_esp32 (esphome v1.16.2)'
}
[15:56:28][VV][api.service:220]: on_ping_request: PingRequest {}
[15:56:28][VV][api.service:032]: send_ping_response: PingResponse {}
[15:56:28][V][app:081]: A component took a long time in a loop() cycle (1.86 s).
[15:56:28][V][app:082]: Components should block for at most 20-30ms in loop().
[15:56:28][VV][scheduler:152]: Running interval 'update' with interval=1000 last_execution=122574 (now=125189)
[15:56:30][VV][api.service:199]: on_connect_request: ConnectRequest {
  password: 'xxxx'
}
[15:56:30][D][api.connection:604]: Client 'Home Assistant 2021.2.3 (192.xxx.xxx.100)' connected successfully!
[15:56:30][VV][api.service:016]: send_connect_response: ConnectResponse {
  invalid_password: NO
}
[15:56:30][V][app:081]: A component took a long time in a loop() cycle (1.84 s).
[15:56:30][V][app:082]: Components should block for at most 20-30ms in loop().
[15:56:30][VV][scheduler:152]: Running interval 'update' with interval=1000 last_execution=124574 (now=127048)
[15:56:32][VV][api.service:234]: on_device_info_request: DeviceInfoRequest {}
[15:56:32][VV][api.service:036]: send_device_info_response: DeviceInfoResponse {
  uses_password: YES
  name: 'test_esp32'
  mac_address: '3C:61:05:2F:D3:FC'
  esphome_version: '1.16.2'
  compilation_time: 'Feb 25 2021, 15:02:00'
  model: 'DOIT ESP32 DEVKIT V1'
  has_deep_sleep: NO
}
[15:56:32][V][app:081]: A component took a long time in a loop() cycle (1.84 s).
[15:56:32][V][app:082]: Components should block for at most 20-30ms in loop().
[15:56:32][VV][scheduler:152]: Running interval 'update' with interval=1000 last_execution=126574 (now=128911)
[15:56:33][VV][api.service:241]: on_list_entities_request: ListEntitiesRequest {}
[15:56:33][VV][api.service:220]: on_ping_request: PingRequest {}
[15:56:33][VV][api.service:032]: send_ping_response: PingResponse {}
[15:56:33][V][app:081]: A component took a long time in a loop() cycle (1.83 s).
[15:56:33][V][app:082]: Components should block for at most 20-30ms in loop().
[15:56:33][VV][scheduler:152]: Running interval 'update' with interval=1000 last_execution=128574 (now=130761)
[15:56:35][V][app:081]: A component took a long time in a loop() cycle (1.82 s).
[15:56:35][V][app:082]: Components should block for at most 20-30ms in loop().
[15:56:35][VV][scheduler:152]: Running interval 'update' with interval=1000 last_execution=130574 (now=132591)
[15:56:37][VV][api.service:206]: on_disconnect_request: DisconnectRequest {}
[15:56:37][VV][api.service:024]: send_disconnect_response: DisconnectResponse {}
[15:56:37][V][app:081]: A component took a long time in a loop() cycle (1.83 s).
[15:56:37][V][app:082]: Components should block for at most 20-30ms in loop().
[15:56:37][VV][scheduler:152]: Running interval 'update' with interval=1000 last_execution=132574 (now=134443)
[15:56:39][D][api:067]: Disconnecting Home Assistant 2021.2.3 (192.xxx.xxx.100)
[15:56:39][VV][api.service:192]: on_hello_request: HelloRequest {
  client_info: 'Home Assistant 2021.2.3'
}
[15:56:39][V][api.connection:588]: Hello from client: 'Home Assistant 2021.2.3 (192.xxx.xxx.100)'
[15:56:39][VV][api.service:012]: send_hello_response: HelloResponse {
  api_version_major: 1
  api_version_minor: 3
  server_info: 'test_esp32 (esphome v1.16.2)'
}
[15:56:39][VV][api.service:220]: on_ping_request: PingRequest {}
[15:56:39][VV][api.service:032]: send_ping_response: PingResponse {}
[15:56:39][V][app:081]: A component took a long time in a loop() cycle (1.85 s).
[15:56:39][V][app:082]: Components should block for at most 20-30ms in loop().
[15:56:39][VV][scheduler:152]: Running interval 'update' with interval=1000 last_execution=133574 (now=136313)
[15:56:41][VV][api.service:199]: on_connect_request: ConnectRequest {
  password: 'xxxx'
}
[15:56:41][D][api.connection:604]: Client 'Home Assistant 2021.2.3 (192.xxx.xxx.100)' connected successfully!
[15:56:41][VV][api.service:016]: send_connect_response: ConnectResponse {
  invalid_password: NO
}
[15:56:41][V][app:081]: A component took a long time in a loop() cycle (1.84 s).
[15:56:41][V][app:082]: Components should block for at most 20-30ms in loop().
[15:56:41][VV][scheduler:152]: Running interval 'update' with interval=1000 last_execution=135574 (now=138170)
[15:56:43][VV][api.service:234]: on_device_info_request: DeviceInfoRequest {}
[15:56:43][VV][api.service:036]: send_device_info_response: DeviceInfoResponse {
  uses_password: YES
  name: 'test_esp32'
  mac_address: '3C:61:05:2F:D3:FC'
  esphome_version: '1.16.2'
  compilation_time: 'Feb 25 2021, 15:02:00'
  model: 'DOIT ESP32 DEVKIT V1'
  has_deep_sleep: NO
}
[15:56:43][V][app:081]: A component took a long time in a loop() cycle (1.85 s).
[15:56:43][V][app:082]: Components should block for at most 20-30ms in loop().
[15:56:43][VV][scheduler:152]: Running interval 'update' with interval=1000 last_execution=137574 (now=140033)
[15:56:45][VV][api.service:241]: on_list_entities_request: ListEntitiesRequest {}
[15:56:45][VV][api.service:220]: on_ping_request: PingRequest {}
[15:56:45][VV][api.service:032]: send_ping_response: PingResponse {}
[15:56:45][V][app:081]: A component took a long time in a loop() cycle (1.83 s).
[15:56:45][V][app:082]: Components should block for at most 20-30ms in loop().
[15:56:45][VV][scheduler:152]: Running interval 'update' with interval=1000 last_execution=139574 (now=141881)
[15:56:46][V][app:081]: A component took a long time in a loop() cycle (1.82 s).
[15:56:46][V][app:082]: Components should block for at most 20-30ms in loop().
[15:56:46][VV][scheduler:152]: Running interval 'update' with interval=1000 last_execution=141574 (now=143709)
[15:56:48][VV][api.service:206]: on_disconnect_request: DisconnectRequest {}
[15:56:48][VV][api.service:024]: send_disconnect_response: DisconnectResponse {}
[15:56:48][V][app:081]: A component took a long time in a loop() cycle (1.83 s).
[15:56:48][V][app:082]: Components should block for at most 20-30ms in loop().
[15:56:48][VV][scheduler:152]: Running interval 'update' with interval=1000 last_execution=143574 (now=145557)
[15:56:50][D][api:067]: Disconnecting Home Assistant 2021.2.3 (192.xxx.xxx.100)
[15:56:50][VV][api.service:192]: on_hello_request: HelloRequest {
  client_info: 'Home Assistant 2021.2.3'
}
[15:56:50][V][api.connection:588]: Hello from client: 'Home Assistant 2021.2.3 (192.xxx.xxx.100)'
[15:56:50][VV][api.service:012]: send_hello_response: HelloResponse {
  api_version_major: 1
  api_version_minor: 3
  server_info: 'test_esp32 (esphome v1.16.2)'
}
[15:56:50][VV][api.service:220]: on_ping_request: PingRequest {}
[15:56:50][VV][api.service:032]: send_ping_response: PingResponse {}
[15:56:50][V][app:081]: A component took a long time in a loop() cycle (1.88 s).
[15:56:50][V][app:082]: Components should block for at most 20-30ms in loop().
[15:56:50][VV][scheduler:152]: Running interval 'update' with interval=1000 last_execution=144574 (now=147455)
[15:56:52][VV][api.service:199]: on_connect_request: ConnectRequest {
  password: 'xxxx'
}
[15:56:52][D][api.connection:604]: Client 'Home Assistant 2021.2.3 (192.xxx.xxx.100)' connected successfully!
[15:56:52][VV][api.service:016]: send_connect_response: ConnectResponse {
  invalid_password: NO
}
[15:56:52][V][app:081]: A component took a long time in a loop() cycle (1.84 s).
[15:56:52][V][app:082]: Components should block for at most 20-30ms in loop().
[15:56:52][VV][scheduler:152]: Running interval 'update' with interval=1000 last_execution=146574 (now=149311)
[15:56:54][VV][api.service:234]: on_device_info_request: DeviceInfoRequest {}
[15:56:54][VV][api.service:036]: send_device_info_response: DeviceInfoResponse {
  uses_password: YES
  name: 'test_esp32'
  mac_address: '3C:61:05:2F:D3:FC'
  esphome_version: '1.16.2'
  compilation_time: 'Feb 25 2021, 15:02:00'
  model: 'DOIT ESP32 DEVKIT V1'
  has_deep_sleep: NO
}
[15:56:54][V][app:081]: A component took a long time in a loop() cycle (1.84 s).
[15:56:54][V][app:082]: Components should block for at most 20-30ms in loop().
[15:56:54][VV][scheduler:152]: Running interval 'update' with interval=1000 last_execution=148574 (now=151174)
[15:56:56][VV][api.service:241]: on_list_entities_request: ListEntitiesRequest {}
[15:56:56][VV][api.service:220]: on_ping_request: PingRequest {}
[15:56:56][VV][api.service:032]: send_ping_response: PingResponse {}
[15:56:56][V][app:081]: A component took a long time in a loop() cycle (1.83 s).
[15:56:56][V][app:082]: Components should block for at most 20-30ms in loop().
[15:56:56][VV][scheduler:152]: Running interval 'update' with interval=1000 last_execution=150574 (now=153021)
[15:56:58][V][app:081]: A component took a long time in a loop() cycle (1.82 s).
[15:56:58][V][app:082]: Components should block for at most 20-30ms in loop().
[15:56:58][VV][scheduler:152]: Running interval 'update' with interval=1000 last_execution=152574 (now=154848)
[15:56:59][VV][api.service:206]: on_disconnect_request: DisconnectRequest {}
[15:56:59][VV][api.service:024]: send_disconnect_response: DisconnectResponse {}
[15:56:59][V][app:081]: A component took a long time in a loop() cycle (1.83 s).
[15:56:59][V][app:082]: Components should block for at most 20-30ms in loop().
[15:56:59][VV][scheduler:152]: Running interval 'update' with interval=1000 last_execution=154574 (now=156705)
[15:57:01][D][api:067]: Disconnecting Home Assistant 2021.2.3 (192.xxx.xxx.100)
[15:57:01][VV][api.service:192]: on_hello_request: HelloRequest {
  client_info: 'Home Assistant 2021.2.3'
}
[15:57:01][V][api.connection:588]: Hello from client: 'Home Assistant 2021.2.3 (192.xxx.xxx.100)'
[15:57:01][VV][api.service:012]: send_hello_response: HelloResponse {
  api_version_major: 1
  api_version_minor: 3
  server_info: 'test_esp32 (esphome v1.16.2)'
}
[15:57:01][VV][api.service:220]: on_ping_request: PingRequest {}
[15:57:01][VV][api.service:032]: send_ping_response: PingResponse {}
[15:57:01][V][app:081]: A component took a long time in a loop() cycle (1.86 s).
[15:57:01][V][app:082]: Components should block for at most 20-30ms in loop().
[15:57:01][VV][scheduler:152]: Running interval 'update' with interval=1000 last_execution=156574 (now=158590)
[15:57:03][VV][api.service:199]: on_connect_request: ConnectRequest {
  password: 'xxxx'
}
[15:57:03][D][api.connection:604]: Client 'Home Assistant 2021.2.3 (192.xxx.xxx.100)' connected successfully!
[15:57:03][VV][api.service:016]: send_connect_response: ConnectResponse {
  invalid_password: NO
}
[15:57:03][V][app:081]: A component took a long time in a loop() cycle (1.84 s).
[15:57:03][V][app:082]: Components should block for at most 20-30ms in loop().
[15:57:03][VV][scheduler:152]: Running interval 'update' with interval=1000 last_execution=158574 (now=160452)
[15:57:05][VV][api.service:234]: on_device_info_request: DeviceInfoRequest {}
[15:57:05][VV][api.service:036]: send_device_info_response: DeviceInfoResponse {
  uses_password: YES
  name: 'test_esp32'
  mac_address: '3C:61:05:2F:D3:FC'
  esphome_version: '1.16.2'
  compilation_time: 'Feb 25 2021, 15:02:00'
  model: 'DOIT ESP32 DEVKIT V1'
  has_deep_sleep: NO
}
[15:57:05][V][app:081]: A component took a long time in a loop() cycle (1.84 s).
[15:57:05][V][app:082]: Components should block for at most 20-30ms in loop().
[15:57:05][VV][scheduler:152]: Running interval 'update' with interval=1000 last_execution=159574 (now=162315)
[15:57:07][VV][api.service:241]: on_list_entities_request: ListEntitiesRequest {}
[15:57:07][VV][api.service:220]: on_ping_request: PingRequest {}
[15:57:07][VV][api.service:032]: send_ping_response: PingResponse {}
[15:57:07][V][app:081]: A component took a long time in a loop() cycle (1.83 s).
[15:57:07][V][app:082]: Components should block for at most 20-30ms in loop().
[15:57:07][VV][scheduler:152]: Running interval 'update' with interval=1000 last_execution=161574 (now=164164)
[15:57:09][V][app:081]: A component took a long time in a loop() cycle (1.82 s).
[15:57:09][V][app:082]: Components should block for at most 20-30ms in loop().
[15:57:09][VV][scheduler:152]: Running interval 'update' with interval=1000 last_execution=163574 (now=165994)
[15:57:10][VV][api.service:206]: on_disconnect_request: DisconnectRequest {}
[15:57:10][VV][api.service:024]: send_disconnect_response: DisconnectResponse {}
[15:57:10][V][app:081]: A component took a long time in a loop() cycle (1.83 s).
[15:57:10][V][app:082]: Components should block for at most 20-30ms in loop().
[15:57:11][VV][scheduler:152]: Running interval 'update' with interval=1000 last_execution=165574 (now=167846)
[15:57:12][D][api:067]: Disconnecting Home Assistant 2021.2.3 (192.xxx.xxx.100)
[15:57:12][VV][api.service:192]: on_hello_request: HelloRequest {
  client_info: 'Home Assistant 2021.2.3'
}
[15:57:12][V][api.connection:588]: Hello from client: 'Home Assistant 2021.2.3 (192.xxx.xxx.100)'
[15:57:12][VV][api.service:012]: send_hello_response: HelloResponse {
  api_version_major: 1
  api_version_minor: 3
  server_info: 'test_esp32 (esphome v1.16.2)'
}
[15:57:12][VV][api.service:220]: on_ping_request: PingRequest {}
[15:57:12][VV][api.service:032]: send_ping_response: PingResponse {}
[15:57:12][V][app:081]: A component took a long time in a loop() cycle (1.87 s).
[15:57:12][V][app:082]: Components should block for at most 20-30ms in loop().
[15:57:12][VV][scheduler:152]: Running interval 'update' with interval=1000 last_execution=167574 (now=169727)
[15:57:14][VV][api.service:199]: on_connect_request: ConnectRequest {
  password: 'xxxx'
}
[15:57:14][D][api.connection:604]: Client 'Home Assistant 2021.2.3 (192.xxx.xxx.100)' connected successfully!
[15:57:14][VV][api.service:016]: send_connect_response: ConnectResponse {
  invalid_password: NO
}
[15:57:14][V][app:081]: A component took a long time in a loop() cycle (1.84 s).
[15:57:14][V][app:082]: Components should block for at most 20-30ms in loop().
[15:57:14][VV][scheduler:152]: Running interval 'update' with interval=1000 last_execution=169574 (now=171585)
[15:57:16][VV][api.service:234]: on_device_info_request: DeviceInfoRequest {}
[15:57:16][VV][api.service:036]: send_device_info_response: DeviceInfoResponse {
  uses_password: YES
  name: 'test_esp32'
  mac_address: '3C:61:05:2F:D3:FC'
  esphome_version: '1.16.2'
  compilation_time: 'Feb 25 2021, 15:02:00'
  model: 'DOIT ESP32 DEVKIT V1'
  has_deep_sleep: NO
}
[15:57:16][V][app:081]: A component took a long time in a loop() cycle (1.84 s).
[15:57:16][V][app:082]: Components should block for at most 20-30ms in loop().
[15:57:16][VV][scheduler:152]: Running interval 'update' with interval=1000 last_execution=171574 (now=173445)
[15:57:18][VV][api.service:241]: on_list_entities_request: ListEntitiesRequest {}
[15:57:18][VV][api.service:220]: on_ping_request: PingRequest {}
[15:57:18][VV][api.service:032]: send_ping_response: PingResponse {}
[15:57:18][V][app:081]: A component took a long time in a loop() cycle (1.83 s).
[15:57:18][V][app:082]: Components should block for at most 20-30ms in loop().
[15:57:18][VV][scheduler:152]: Running interval 'update' with interval=1000 last_execution=172574 (now=175292)
[15:57:20][V][app:081]: A component took a long time in a loop() cycle (1.82 s).
[15:57:20][V][app:082]: Components should block for at most 20-30ms in loop().
[15:57:20][VV][scheduler:152]: Running interval 'update' with interval=1000 last_execution=174574 (now=177120)
[15:57:22][VV][api.service:206]: on_disconnect_request: DisconnectRequest {}
[15:57:22][VV][api.service:024]: send_disconnect_response: DisconnectResponse {}
[15:57:22][V][app:081]: A component took a long time in a loop() cycle (1.83 s).
[15:57:22][V][app:082]: Components should block for at most 20-30ms in loop().
[15:57:22][VV][scheduler:152]: Running interval 'update' with interval=1000 last_execution=176574 (now=178974)
[15:57:23][D][api:067]: Disconnecting Home Assistant 2021.2.3 (192.xxx.xxx.100)
[15:57:23][VV][api.service:192]: on_hello_request: HelloRequest {
  client_info: 'Home Assistant 2021.2.3'
}
[15:57:23][V][api.connection:588]: Hello from client: 'Home Assistant 2021.2.3 (192.xxx.xxx.100)'
[15:57:23][VV][api.service:012]: send_hello_response: HelloResponse {
  api_version_major: 1
  api_version_minor: 3
  server_info: 'test_esp32 (esphome v1.16.2)'
}
[15:57:23][VV][api.service:220]: on_ping_request: PingRequest {}
[15:57:23][VV][api.service:032]: send_ping_response: PingResponse {}
[15:57:24][V][app:081]: A component took a long time in a loop() cycle (1.86 s).
[15:57:24][V][app:082]: Components should block for at most 20-30ms in loop().
[15:57:24][VV][scheduler:152]: Running interval 'update' with interval=1000 last_execution=178574 (now=180858)
[15:57:25][VV][api.service:199]: on_connect_request: ConnectRequest {
  password: 'xxxx'
}
[15:57:25][D][api.connection:604]: Client 'Home Assistant 2021.2.3 (192.xxx.xxx.100)' connected successfully!
[15:57:25][VV][api.service:016]: send_connect_response: ConnectResponse {
  invalid_password: NO
}
[15:57:25][V][app:081]: A component took a long time in a loop() cycle (1.84 s).
[15:57:25][V][app:082]: Components should block for at most 20-30ms in loop().
[15:57:25][VV][scheduler:152]: Running interval 'update' with interval=1000 last_execution=180574 (now=182716)
[15:57:27][VV][api.service:234]: on_device_info_request: DeviceInfoRequest {}
[15:57:27][VV][api.service:036]: send_device_info_response: DeviceInfoResponse {
  uses_password: YES
  name: 'test_esp32'
  mac_address: '3C:61:05:2F:D3:FC'
  esphome_version: '1.16.2'
  compilation_time: 'Feb 25 2021, 15:02:00'
  model: 'DOIT ESP32 DEVKIT V1'
  has_deep_sleep: NO
}
[15:57:27][V][app:081]: A component took a long time in a loop() cycle (1.84 s).
[15:57:27][V][app:082]: Components should block for at most 20-30ms in loop().
[15:57:27][VV][scheduler:152]: Running interval 'update' with interval=1000 last_execution=182574 (now=184575)
[15:57:29][VV][api.service:241]: on_list_entities_request: ListEntitiesRequest {}
[15:57:29][VV][api.service:220]: on_ping_request: PingRequest {}
[15:57:29][VV][api.service:032]: send_ping_response: PingResponse {}
[15:57:29][V][app:081]: A component took a long time in a loop() cycle (1.83 s).
[15:57:29][V][app:082]: Components should block for at most 20-30ms in loop().
[15:57:29][VV][scheduler:152]: Running interval 'update' with interval=1000 last_execution=184574 (now=186424)
[15:57:31][V][app:081]: A component took a long time in a loop() cycle (1.82 s).
[15:57:31][V][app:082]: Components should block for at most 20-30ms in loop().
[15:57:31][VV][scheduler:152]: Running interval 'update' with interval=1000 last_execution=185574 (now=188255)
[15:57:33][VV][api.service:206]: on_disconnect_request: DisconnectRequest {}
[15:57:33][VV][api.service:024]: send_disconnect_response: DisconnectResponse {}
[15:57:33][V][app:081]: A component took a long time in a loop() cycle (1.82 s).
[15:57:33][V][app:082]: Components should block for at most 20-30ms in loop().
[15:57:33][VV][scheduler:152]: Running interval 'update' with interval=1000 last_execution=187574 (now=190104)
[15:57:35][D][api:067]: Disconnecting Home Assistant 2021.2.3 (192.xxx.xxx.100)
[15:57:35][VV][api.service:192]: on_hello_request: HelloRequest {
  client_info: 'Home Assistant 2021.2.3'
}
[15:57:35][V][api.connection:588]: Hello from client: 'Home Assistant 2021.2.3 (192.xxx.xxx.100)'
[15:57:35][VV][api.service:012]: send_hello_response: HelloResponse {
  api_version_major: 1
  api_version_minor: 3
  server_info: 'test_esp32 (esphome v1.16.2)'
}
[15:57:35][VV][api.service:220]: on_ping_request: PingRequest {}
[15:57:35][VV][api.service:032]: send_ping_response: PingResponse {}
[15:57:35][V][app:081]: A component took a long time in a loop() cycle (1.87 s).
[15:57:35][V][app:082]: Components should block for at most 20-30ms in loop().
[15:57:35][VV][scheduler:152]: Running interval 'update' with interval=1000 last_execution=189574 (now=191987)
[15:57:36][VV][api.service:199]: on_connect_request: ConnectRequest {
  password: 'xxxx'
}
[15:57:36][D][api.connection:604]: Client 'Home Assistant 2021.2.3 (192.xxx.xxx.100)' connected successfully!
[15:57:36][VV][api.service:016]: send_connect_response: ConnectResponse {
  invalid_password: NO
}
[15:57:36][V][app:081]: A component took a long time in a loop() cycle (1.84 s).
[15:57:36][V][app:082]: Components should block for at most 20-30ms in loop().
[15:57:37][VV][scheduler:152]: Running interval 'update' with interval=1000 last_execution=191574 (now=193842)
[15:57:38][VV][api.service:234]: on_device_info_request: DeviceInfoRequest {}
[15:57:38][VV][api.service:036]: send_device_info_response: DeviceInfoResponse {
  uses_password: YES
  name: 'test_esp32'
  mac_address: '3C:61:05:2F:D3:FC'
  esphome_version: '1.16.2'
  compilation_time: 'Feb 25 2021, 15:02:00'
  model: 'DOIT ESP32 DEVKIT V1'
  has_deep_sleep: NO
}
[15:57:38][V][app:081]: A component took a long time in a loop() cycle (1.84 s).
[15:57:38][V][app:082]: Components should block for at most 20-30ms in loop().
[15:57:38][VV][scheduler:152]: Running interval 'update' with interval=1000 last_execution=193574 (now=195705)
[15:57:40][VV][api.service:241]: on_list_entities_request: ListEntitiesRequest {}
[15:57:40][VV][api.service:220]: on_ping_request: PingRequest {}
[15:57:40][VV][api.service:032]: send_ping_response: PingResponse {}
[15:57:40][V][app:081]: A component took a long time in a loop() cycle (1.83 s).
[15:57:40][V][app:082]: Components should block for at most 20-30ms in loop().
[15:57:40][VV][scheduler:152]: Running interval 'update' with interval=1000 last_execution=195574 (now=197555)
[15:57:42][V][app:081]: A component took a long time in a loop() cycle (1.82 s).
[15:57:42][V][app:082]: Components should block for at most 20-30ms in loop().
[15:57:42][VV][scheduler:152]: Running interval 'update' with interval=1000 last_execution=196574 (now=199384)
[15:57:44][VV][api.service:206]: on_disconnect_request: DisconnectRequest {}
[15:57:44][VV][api.service:024]: send_disconnect_response: DisconnectResponse {}
[15:57:44][V][app:081]: A component took a long time in a loop() cycle (1.82 s).
[15:57:44][V][app:082]: Components should block for at most 20-30ms in loop().
[15:57:44][VV][scheduler:152]: Running interval 'update' with interval=1000 last_execution=198574 (now=201233)
[15:57:46][D][api:067]: Disconnecting Home Assistant 2021.2.3 (192.xxx.xxx.100)
[15:57:46][VV][api.service:192]: on_hello_request: HelloRequest {
  client_info: 'Home Assistant 2021.2.3'
}
[15:57:46][V][api.connection:588]: Hello from client: 'Home Assistant 2021.2.3 (192.xxx.xxx.100)'
[15:57:46][VV][api.service:012]: send_hello_response: HelloResponse {
  api_version_major: 1
  api_version_minor: 3
  server_info: 'test_esp32 (esphome v1.16.2)'
}
[15:57:46][VV][api.service:220]: on_ping_request: PingRequest {}
[15:57:46][VV][api.service:032]: send_ping_response: PingResponse {}
[15:57:46][V][app:081]: A component took a long time in a loop() cycle (1.86 s).
[15:57:46][V][app:082]: Components should block for at most 20-30ms in loop().
[15:57:46][VV][scheduler:152]: Running interval 'update' with interval=1000 last_execution=200574 (now=203111)
[15:57:48][VV][api.service:199]: on_connect_request: ConnectRequest {
  password: 'xxxx'
}
[15:57:48][D][api.connection:604]: Client 'Home Assistant 2021.2.3 (192.xxx.xxx.100)' connected successfully!
[15:57:48][VV][api.service:016]: send_connect_response: ConnectResponse {
  invalid_password: NO
}
[15:57:48][V][app:081]: A component took a long time in a loop() cycle (1.84 s).
[15:57:48][V][app:082]: Components should block for at most 20-30ms in loop().
[15:57:48][VV][scheduler:152]: Running interval 'update' with interval=1000 last_execution=202574 (now=204967)
[15:57:49][VV][api.service:234]: on_device_info_request: DeviceInfoRequest {}
[15:57:49][VV][api.service:036]: send_device_info_response: DeviceInfoResponse {
  uses_password: YES
  name: 'test_esp32'
  mac_address: '3C:61:05:2F:D3:FC'
  esphome_version: '1.16.2'
  compilation_time: 'Feb 25 2021, 15:02:00'
  model: 'DOIT ESP32 DEVKIT V1'
  has_deep_sleep: NO
}
[15:57:49][V][app:081]: A component took a long time in a loop() cycle (1.84 s).
[15:57:49][V][app:082]: Components should block for at most 20-30ms in loop().
[15:57:49][VV][scheduler:152]: Running interval 'update' with interval=1000 last_execution=204574 (now=206829)
[15:57:51][VV][api.service:241]: on_list_entities_request: ListEntitiesRequest {}
[15:57:51][VV][api.service:220]: on_ping_request: PingRequest {}
[15:57:51][VV][api.service:032]: send_ping_response: PingResponse {}
[15:57:51][V][app:081]: A component took a long time in a loop() cycle (1.83 s).
[15:57:51][V][app:082]: Components should block for at most 20-30ms in loop().
[15:57:51][VV][scheduler:152]: Running interval 'update' with interval=1000 last_execution=206574 (now=208679)
[15:57:53][V][app:081]: A component took a long time in a loop() cycle (1.82 s).
[15:57:53][V][app:082]: Components should block for at most 20-30ms in loop().
[15:57:53][VV][scheduler:152]: Running interval 'update' with interval=1000 last_execution=208574 (now=210512)
[15:57:55][VV][api.service:206]: on_disconnect_request: DisconnectRequest {}
[15:57:55][VV][api.service:024]: send_disconnect_response: DisconnectResponse {}
[15:57:55][V][app:081]: A component took a long time in a loop() cycle (1.82 s).
[15:57:55][V][app:082]: Components should block for at most 20-30ms in loop().
[15:57:55][VV][scheduler:152]: Running interval 'update' with interval=1000 last_execution=209574 (now=212350)
[15:57:57][D][api:067]: Disconnecting Home Assistant 2021.2.3 (192.xxx.xxx.100)
[15:57:57][VV][api.service:192]: on_hello_request: HelloRequest {
  client_info: 'Home Assistant 2021.2.3'
}
[15:57:57][V][api.connection:588]: Hello from client: 'Home Assistant 2021.2.3 (192.xxx.xxx.100)'
[15:57:57][VV][api.service:012]: send_hello_response: HelloResponse {
  api_version_major: 1
  api_version_minor: 3
  server_info: 'test_esp32 (esphome v1.16.2)'
}
[15:57:57][VV][api.service:220]: on_ping_request: PingRequest {}
[15:57:57][VV][api.service:032]: send_ping_response: PingResponse {}
[15:57:57][V][app:081]: A component took a long time in a loop() cycle (1.87 s).
[15:57:57][V][app:082]: Components should block for at most 20-30ms in loop().
[15:57:57][VV][scheduler:152]: Running interval 'update' with interval=1000 last_execution=211574 (now=214235)
[15:57:59][VV][api.service:199]: on_connect_request: ConnectRequest {
  password: 'xxxx'
}
[15:57:59][D][api.connection:604]: Client 'Home Assistant 2021.2.3 (192.xxx.xxx.100)' connected successfully!
[15:57:59][VV][api.service:016]: send_connect_response: ConnectResponse {
  invalid_password: NO
}
[15:57:59][V][app:081]: A component took a long time in a loop() cycle (1.83 s).
[15:57:59][V][app:082]: Components should block for at most 20-30ms in loop().
[15:57:59][VV][scheduler:152]: Running interval 'update' with interval=1000 last_execution=213574 (now=216088)
[15:58:01][VV][api.service:234]: on_device_info_request: DeviceInfoRequest {}
[15:58:01][VV][api.service:036]: send_device_info_response: DeviceInfoResponse {
  uses_password: YES
  name: 'test_esp32'
  mac_address: '3C:61:05:2F:D3:FC'
  esphome_version: '1.16.2'
  compilation_time: 'Feb 25 2021, 15:02:00'
  model: 'DOIT ESP32 DEVKIT V1'
  has_deep_sleep: NO
}
[15:58:01][V][app:081]: A component took a long time in a loop() cycle (1.85 s).
[15:58:01][V][app:082]: Components should block for at most 20-30ms in loop().
[15:58:01][VV][scheduler:152]: Running interval 'update' with interval=1000 last_execution=215574 (now=217954)
[15:58:02][VV][api.service:241]: on_list_entities_request: ListEntitiesRequest {}
[15:58:02][VV][api.service:220]: on_ping_request: PingRequest {}
[15:58:02][VV][api.service:032]: send_ping_response: PingResponse {}
[15:58:02][V][app:081]: A component took a long time in a loop() cycle (1.82 s).
[15:58:02][V][app:082]: Components should block for at most 20-30ms in loop().
[15:58:02][VV][scheduler:152]: Running interval 'update' with interval=1000 last_execution=217574 (now=219797)
[15:58:04][V][app:081]: A component took a long time in a loop() cycle (1.82 s).
[15:58:04][V][app:082]: Components should block for at most 20-30ms in loop().
[15:58:04][VV][scheduler:152]: Running interval 'update' with interval=1000 last_execution=219574 (now=221626)
[15:58:06][VV][api.service:206]: on_disconnect_request: DisconnectRequest {}
[15:58:06][VV][api.service:024]: send_disconnect_response: DisconnectResponse {}
[15:58:06][V][app:081]: A component took a long time in a loop() cycle (1.83 s).
[15:58:06][V][app:082]: Components should block for at most 20-30ms in loop().
[15:58:06][VV][scheduler:152]: Running interval 'update' with interval=1000 last_execution=221574 (now=223481)
[15:58:08][D][api:067]: Disconnecting Home Assistant 2021.2.3 (192.xxx.xxx.100)
[15:58:08][VV][api.service:192]: on_hello_request: HelloRequest {
  client_info: 'Home Assistant 2021.2.3'
}
[15:58:08][V][api.connection:588]: Hello from client: 'Home Assistant 2021.2.3 (192.xxx.xxx.100)'
[15:58:08][VV][api.service:012]: send_hello_response: HelloResponse {
  api_version_major: 1
  api_version_minor: 3
  server_info: 'test_esp32 (esphome v1.16.2)'
}
[15:58:08][VV][api.service:220]: on_ping_request: PingRequest {}
[15:58:08][VV][api.service:032]: send_ping_response: PingResponse {}
[15:58:08][V][app:081]: A component took a long time in a loop() cycle (1.85 s).
[15:58:08][V][app:082]: Components should block for at most 20-30ms in loop().
[15:58:08][VV][scheduler:152]: Running interval 'update' with interval=1000 last_execution=222574 (now=225357)
[15:58:10][VV][api.service:199]: on_connect_request: ConnectRequest {
  password: 'xxxx'
}
[15:58:10][D][api.connection:604]: Client 'Home Assistant 2021.2.3 (192.xxx.xxx.100)' connected successfully!
[15:58:10][VV][api.service:016]: send_connect_response: ConnectResponse {
  invalid_password: NO
}
[15:58:10][V][app:081]: A component took a long time in a loop() cycle (1.84 s).
[15:58:10][V][app:082]: Components should block for at most 20-30ms in loop().
[15:58:10][VV][scheduler:152]: Running interval 'update' with interval=1000 last_execution=224574 (now=227215)
[15:58:12][VV][api.service:234]: on_device_info_request: DeviceInfoRequest {}
[15:58:12][VV][api.service:036]: send_device_info_response: DeviceInfoResponse {
  uses_password: YES
  name: 'test_esp32'
  mac_address: '3C:61:05:2F:D3:FC'
  esphome_version: '1.16.2'
  compilation_time: 'Feb 25 2021, 15:02:00'
  model: 'DOIT ESP32 DEVKIT V1'
  has_deep_sleep: NO
}
[15:58:12][V][app:081]: A component took a long time in a loop() cycle (1.84 s).
[15:58:12][V][app:082]: Components should block for at most 20-30ms in loop().
[15:58:12][VV][scheduler:152]: Running interval 'update' with interval=1000 last_execution=226574 (now=229078)
[15:58:14][VV][api.service:241]: on_list_entities_request: ListEntitiesRequest {}
[15:58:14][VV][api.service:220]: on_ping_request: PingRequest {}
[15:58:14][VV][api.service:032]: send_ping_response: PingResponse {}
[15:58:14][V][app:081]: A component took a long time in a loop() cycle (1.84 s).
[15:58:14][V][app:082]: Components should block for at most 20-30ms in loop().
[15:58:14][VV][scheduler:152]: Running interval 'update' with interval=1000 last_execution=228574 (now=230932)
[15:58:15][V][app:081]: A component took a long time in a loop() cycle (1.82 s).
[15:58:15][V][app:082]: Components should block for at most 20-30ms in loop().
[15:58:15][VV][scheduler:152]: Running interval 'update' with interval=1000 last_execution=230574 (now=232762)
[15:58:17][VV][api.service:206]: on_disconnect_request: DisconnectRequest {}
[15:58:17][VV][api.service:024]: send_disconnect_response: DisconnectResponse {}
[15:58:17][V][app:081]: A component took a long time in a loop() cycle (1.82 s).
[15:58:17][V][app:082]: Components should block for at most 20-30ms in loop().
[15:58:17][VV][scheduler:152]: Running interval 'update' with interval=1000 last_execution=232574 (now=234619)
[15:58:19][D][api:067]: Disconnecting Home Assistant 2021.2.3 (192.xxx.xxx.100)
[15:58:19][VV][api.service:192]: on_hello_request: HelloRequest {
  client_info: 'Home Assistant 2021.2.3'
}
[15:58:19][V][api.connection:588]: Hello from client: 'Home Assistant 2021.2.3 (192.xxx.xxx.100)'
[15:58:19][VV][api.service:012]: send_hello_response: HelloResponse {
  api_version_major: 1
  api_version_minor: 3
  server_info: 'test_esp32 (esphome v1.16.2)'
}
[15:58:19][VV][api.service:220]: on_ping_request: PingRequest {}
[15:58:19][VV][api.service:032]: send_ping_response: PingResponse {}
[15:58:19][V][app:081]: A component took a long time in a loop() cycle (1.86 s).
[15:58:19][V][app:082]: Components should block for at most 20-30ms in loop().
[15:58:19][VV][scheduler:152]: Running interval 'update' with interval=1000 last_execution=234574 (now=236501)
[15:58:21][VV][api.service:199]: on_connect_request: ConnectRequest {
  password: 'xxxx'
}
[15:58:21][D][api.connection:604]: Client 'Home Assistant 2021.2.3 (192.xxx.xxx.100)' connected successfully!
[15:58:21][VV][api.service:016]: send_connect_response: ConnectResponse {
  invalid_password: NO
}
[15:58:21][V][app:081]: A component took a long time in a loop() cycle (1.84 s).
[15:58:21][V][app:082]: Components should block for at most 20-30ms in loop().
[15:58:21][VV][scheduler:152]: Running interval 'update' with interval=1000 last_execution=235574 (now=238361)
[15:58:23][VV][api.service:234]: on_device_info_request: DeviceInfoRequest {}
[15:58:23][VV][api.service:036]: send_device_info_response: DeviceInfoResponse {
  uses_password: YES
  name: 'test_esp32'
  mac_address: '3C:61:05:2F:D3:FC'
  esphome_version: '1.16.2'
  compilation_time: 'Feb 25 2021, 15:02:00'
  model: 'DOIT ESP32 DEVKIT V1'
  has_deep_sleep: NO
}
[15:58:23][V][app:081]: A component took a long time in a loop() cycle (1.84 s).
[15:58:23][V][app:082]: Components should block for at most 20-30ms in loop().
[15:58:23][VV][scheduler:152]: Running interval 'update' with interval=1000 last_execution=237574 (now=240219)
[15:58:25][VV][api.service:241]: on_list_entities_request: ListEntitiesRequest {}
[15:58:25][VV][api.service:220]: on_ping_request: PingRequest {}
[15:58:25][VV][api.service:032]: send_ping_response: PingResponse {}
[15:58:25][V][app:081]: A component took a long time in a loop() cycle (1.83 s).
[15:58:25][V][app:082]: Components should block for at most 20-30ms in loop().
[15:58:25][VV][scheduler:152]: Running interval 'update' with interval=1000 last_execution=239574 (now=242070)
[15:58:27][V][app:081]: A component took a long time in a loop() cycle (1.82 s).
[15:58:27][V][app:082]: Components should block for at most 20-30ms in loop().
[15:58:27][VV][scheduler:152]: Running interval 'update' with interval=1000 last_execution=241574 (now=243904)
[15:58:28][VV][api.service:206]: on_disconnect_request: DisconnectRequest {}
[15:58:28][VV][api.service:024]: send_disconnect_response: DisconnectResponse {}
[15:58:28][V][app:081]: A component took a long time in a loop() cycle (1.83 s).
[15:58:28][V][app:082]: Components should block for at most 20-30ms in loop().
[15:58:28][VV][scheduler:152]: Running interval 'update' with interval=1000 last_execution=243574 (now=245757)
[15:58:30][D][api:067]: Disconnecting Home Assistant 2021.2.3 (192.xxx.xxx.100)
[15:58:30][VV][api.service:192]: on_hello_request: HelloRequest {
  client_info: 'Home Assistant 2021.2.3'
}
[15:58:30][V][api.connection:588]: Hello from client: 'Home Assistant 2021.2.3 (192.xxx.xxx.100)'
[15:58:30][VV][api.service:012]: send_hello_response: HelloResponse {
  api_version_major: 1
  api_version_minor: 3
  server_info: 'test_esp32 (esphome v1.16.2)'
}
[15:58:30][VV][api.service:220]: on_ping_request: PingRequest {}
[15:58:30][VV][api.service:032]: send_ping_response: PingResponse {}
[15:58:30][V][app:081]: A component took a long time in a loop() cycle (1.86 s).
[15:58:30][V][app:082]: Components should block for at most 20-30ms in loop().
[15:58:30][VV][scheduler:152]: Running interval 'update' with interval=1000 last_execution=245574 (now=247632)
[15:58:32][VV][api.service:199]: on_connect_request: ConnectRequest {
  password: 'xxxx'
}
[15:58:32][D][api.connection:604]: Client 'Home Assistant 2021.2.3 (192.xxx.xxx.100)' connected successfully!
[15:58:32][VV][api.service:016]: send_connect_response: ConnectResponse {
  invalid_password: NO
}
[15:58:32][V][app:081]: A component took a long time in a loop() cycle (1.84 s).
[15:58:32][V][app:082]: Components should block for at most 20-30ms in loop().
[15:58:32][VV][scheduler:152]: Running interval 'update' with interval=1000 last_execution=247574 (now=249495)
[15:58:34][VV][api.service:234]: on_device_info_request: DeviceInfoRequest {}
[15:58:34][VV][api.service:036]: send_device_info_response: DeviceInfoResponse {
  uses_password: YES
  name: 'test_esp32'
  mac_address: '3C:61:05:2F:D3:FC'
  esphome_version: '1.16.2'
  compilation_time: 'Feb 25 2021, 15:02:00'
  model: 'DOIT ESP32 DEVKIT V1'
  has_deep_sleep: NO
}
[15:58:34][V][app:081]: A component took a long time in a loop() cycle (1.84 s).
[15:58:34][V][app:082]: Components should block for at most 20-30ms in loop().
[15:58:34][VV][scheduler:152]: Running interval 'update' with interval=1000 last_execution=248574 (now=251361)
[15:58:36][VV][api.service:241]: on_list_entities_request: ListEntitiesRequest {}
[15:58:36][VV][api.service:220]: on_ping_request: PingRequest {}
[15:58:36][VV][api.service:032]: send_ping_response: PingResponse {}
[15:58:36][V][app:081]: A component took a long time in a loop() cycle (1.82 s).
[15:58:36][V][app:082]: Components should block for at most 20-30ms in loop().
[15:58:36][VV][scheduler:152]: Running interval 'update' with interval=1000 last_execution=250574 (now=253207)
[15:58:38][V][app:081]: A component took a long time in a loop() cycle (1.82 s).
[15:58:38][V][app:082]: Components should block for at most 20-30ms in loop().
[15:58:38][VV][scheduler:152]: Running interval 'update' with interval=1000 last_execution=252574 (now=255035)
[15:58:40][VV][api.service:206]: on_disconnect_request: DisconnectRequest {}
[15:58:40][VV][api.service:024]: send_disconnect_response: DisconnectResponse {}
[15:58:40][V][app:081]: A component took a long time in a loop() cycle (1.82 s).
[15:58:40][V][app:082]: Components should block for at most 20-30ms in loop().
[15:58:40][VV][scheduler:152]: Running interval 'update' with interval=1000 last_execution=254574 (now=256878)
[15:58:41][D][api:067]: Disconnecting Home Assistant 2021.2.3 (192.xxx.xxx.100)
[15:58:41][VV][api.service:192]: on_hello_request: HelloRequest {
  client_info: 'Home Assistant 2021.2.3'
}
[15:58:41][V][api.connection:588]: Hello from client: 'Home Assistant 2021.2.3 (192.xxx.xxx.100)'
[15:58:41][VV][api.service:012]: send_hello_response: HelloResponse {
  api_version_major: 1
  api_version_minor: 3
  server_info: 'test_esp32 (esphome v1.16.2)'
}
[15:58:41][VV][api.service:220]: on_ping_request: PingRequest {}
[15:58:41][VV][api.service:032]: send_ping_response: PingResponse {}
[15:58:41][V][app:081]: A component took a long time in a loop() cycle (1.87 s).
[15:58:41][V][app:082]: Components should block for at most 20-30ms in loop().
[15:58:41][VV][scheduler:152]: Running interval 'update' with interval=1000 last_execution=256574 (now=258770)
[15:58:43][VV][api.service:199]: on_connect_request: ConnectRequest {
  password: 'xxxx'
}
[15:58:43][D][api.connection:604]: Client 'Home Assistant 2021.2.3 (192.xxx.xxx.100)' connected successfully!
[15:58:43][VV][api.service:016]: send_connect_response: ConnectResponse {
  invalid_password: NO
}
[15:58:43][V][app:081]: A component took a long time in a loop() cycle (1.84 s).
[15:58:43][V][app:082]: Components should block for at most 20-30ms in loop().
[15:58:43][VV][scheduler:152]: Running interval 'update' with interval=1000 last_execution=258574 (now=260624)
[15:58:45][VV][api.service:234]: on_device_info_request: DeviceInfoRequest {}
[15:58:45][VV][api.service:036]: send_device_info_response: DeviceInfoResponse {
  uses_password: YES
  name: 'test_esp32'
  mac_address: '3C:61:05:2F:D3:FC'
  esphome_version: '1.16.2'
  compilation_time: 'Feb 25 2021, 15:02:00'
  model: 'DOIT ESP32 DEVKIT V1'
  has_deep_sleep: NO
}
[15:58:45][V][app:081]: A component took a long time in a loop() cycle (1.85 s).
[15:58:45][V][app:082]: Components should block for at most 20-30ms in loop().
[15:58:45][VV][scheduler:152]: Running interval 'update' with interval=1000 last_execution=260574 (now=262489)
[15:58:47][VV][api.service:241]: on_list_entities_request: ListEntitiesRequest {}
[15:58:47][VV][api.service:220]: on_ping_request: PingRequest {}
[15:58:47][VV][api.service:032]: send_ping_response: PingResponse {}
[15:58:47][V][app:081]: A component took a long time in a loop() cycle (1.83 s).
[15:58:47][V][app:082]: Components should block for at most 20-30ms in loop().
[15:58:47][VV][scheduler:152]: Running interval 'update' with interval=1000 last_execution=261574 (now=264335)
[15:58:49][V][app:081]: A component took a long time in a loop() cycle (1.81 s).
[15:58:49][V][app:082]: Components should block for at most 20-30ms in loop().
[15:58:49][VV][scheduler:152]: Running interval 'update' with interval=1000 last_execution=263574 (now=266161)
[15:58:51][VV][api.service:206]: on_disconnect_request: DisconnectRequest {}
[15:58:51][VV][api.service:024]: send_disconnect_response: DisconnectResponse {}
[15:58:51][V][app:081]: A component took a long time in a loop() cycle (1.82 s).
[15:58:51][V][app:082]: Components should block for at most 20-30ms in loop().
[15:58:51][VV][scheduler:152]: Running interval 'update' with interval=1000 last_execution=265574 (now=268005)
[15:58:52][D][api:067]: Disconnecting Home Assistant 2021.2.3 (192.xxx.xxx.100)
[15:58:52][VV][api.service:192]: on_hello_request: HelloRequest {
  client_info: 'Home Assistant 2021.2.3'
}
[15:58:52][V][api.connection:588]: Hello from client: 'Home Assistant 2021.2.3 (192.xxx.xxx.100)'
[15:58:53][VV][api.service:012]: send_hello_response: HelloResponse {
  api_version_major: 1
  api_version_minor: 3
  server_info: 'test_esp32 (esphome v1.16.2)'
}
[15:58:53][VV][api.service:220]: on_ping_request: PingRequest {}
[15:58:53][VV][api.service:032]: send_ping_response: PingResponse {}
[15:58:53][V][app:081]: A component took a long time in a loop() cycle (1.86 s).
[15:58:53][V][app:082]: Components should block for at most 20-30ms in loop().
[15:58:53][VV][scheduler:152]: Running interval 'update' with interval=1000 last_execution=267574 (now=269885)
[15:58:54][VV][api.service:199]: on_connect_request: ConnectRequest {
  password: 'xxxx'
}
[15:58:54][D][api.connection:604]: Client 'Home Assistant 2021.2.3 (192.xxx.xxx.100)' connected successfully!
[15:58:54][VV][api.service:016]: send_connect_response: ConnectResponse {
  invalid_password: NO
}
[15:58:54][V][app:081]: A component took a long time in a loop() cycle (1.83 s).
[15:58:54][V][app:082]: Components should block for at most 20-30ms in loop().
[15:58:54][VV][scheduler:152]: Running interval 'update' with interval=1000 last_execution=269574 (now=271738)
[15:58:56][VV][api.service:234]: on_device_info_request: DeviceInfoRequest {}
[15:58:56][VV][api.service:036]: send_device_info_response: DeviceInfoResponse {
  uses_password: YES
  name: 'test_esp32'
  mac_address: '3C:61:05:2F:D3:FC'
  esphome_version: '1.16.2'
  compilation_time: 'Feb 25 2021, 15:02:00'
  model: 'DOIT ESP32 DEVKIT V1'
  has_deep_sleep: NO
}
[15:58:56][V][app:081]: A component took a long time in a loop() cycle (1.84 s).
[15:58:56][V][app:082]: Components should block for at most 20-30ms in loop().
[15:58:56][VV][scheduler:152]: Running interval 'update' with interval=1000 last_execution=271574 (now=273599)
[15:58:58][VV][api.service:241]: on_list_entities_request: ListEntitiesRequest {}
[15:58:58][VV][api.service:220]: on_ping_request: PingRequest {}
[15:58:58][VV][api.service:032]: send_ping_response: PingResponse {}
[15:58:58][V][app:081]: A component took a long time in a loop() cycle (1.84 s).
[15:58:58][V][app:082]: Components should block for at most 20-30ms in loop().
[15:58:58][VV][scheduler:152]: Running interval 'update' with interval=1000 last_execution=273574 (now=275455)
[15:59:00][V][app:081]: A component took a long time in a loop() cycle (1.81 s).
[15:59:00][V][app:082]: Components should block for at most 20-30ms in loop().
[15:59:00][VV][scheduler:152]: Running interval 'update' with interval=1000 last_execution=274574 (now=277282)
[15:59:02][VV][api.service:206]: on_disconnect_request: DisconnectRequest {}
[15:59:02][VV][api.service:024]: send_disconnect_response: DisconnectResponse {}
[15:59:02][V][app:081]: A component took a long time in a loop() cycle (1.80 s).
[15:59:02][V][app:082]: Components should block for at most 20-30ms in loop().
[15:59:02][VV][scheduler:152]: Running interval 'update' with interval=1000 last_execution=276574 (now=279103)
[15:59:04][D][api:067]: Disconnecting Home Assistant 2021.2.3 (192.xxx.xxx.100)
[15:59:04][VV][api.service:192]: on_hello_request: HelloRequest {
  client_info: 'Home Assistant 2021.2.3'
}
[15:59:04][V][api.connection:588]: Hello from client: 'Home Assistant 2021.2.3 (192.xxx.xxx.100)'
[15:59:04][VV][api.service:012]: send_hello_response: HelloResponse {
  api_version_major: 1
  api_version_minor: 3
  server_info: 'test_esp32 (esphome v1.16.2)'
}
[15:59:04][VV][api.service:220]: on_ping_request: PingRequest {}
[15:59:04][VV][api.service:032]: send_ping_response: PingResponse {}
[15:59:04][V][app:081]: A component took a long time in a loop() cycle (1.84 s).
[15:59:04][V][app:082]: Components should block for at most 20-30ms in loop().
[15:59:04][VV][scheduler:152]: Running interval 'update' with interval=1000 last_execution=278574 (now=280963)
[15:59:05][VV][api.service:199]: on_connect_request: ConnectRequest {
  password: 'xxxx'
}
[15:59:05][D][api.connection:604]: Client 'Home Assistant 2021.2.3 (192.xxx.xxx.100)' connected successfully!
[15:59:05][VV][api.service:016]: send_connect_response: ConnectResponse {
  invalid_password: NO
}
[15:59:05][V][app:081]: A component took a long time in a loop() cycle (1.81 s).
[15:59:05][V][app:082]: Components should block for at most 20-30ms in loop().
[15:59:05][VV][scheduler:152]: Running interval 'update' with interval=1000 last_execution=280574 (now=282793)
[15:59:07][VV][api.service:234]: on_device_info_request: DeviceInfoRequest {}
[15:59:07][VV][api.service:036]: send_device_info_response: DeviceInfoResponse {
  uses_password: YES
  name: 'test_esp32'
  mac_address: '3C:61:05:2F:D3:FC'
  esphome_version: '1.16.2'
  compilation_time: 'Feb 25 2021, 15:02:00'
  model: 'DOIT ESP32 DEVKIT V1'
  has_deep_sleep: NO
}
[15:59:07][V][app:081]: A component took a long time in a loop() cycle (1.85 s).
[15:59:07][V][app:082]: Components should block for at most 20-30ms in loop().
[15:59:07][VV][scheduler:152]: Running interval 'update' with interval=1000 last_execution=282574 (now=284658)
[15:59:09][VV][api.service:241]: on_list_entities_request: ListEntitiesRequest {}
[15:59:09][VV][api.service:220]: on_ping_request: PingRequest {}
[15:59:09][VV][api.service:032]: send_ping_response: PingResponse {}
[15:59:09][V][app:081]: A component took a long time in a loop() cycle (1.84 s).
[15:59:09][V][app:082]: Components should block for at most 20-30ms in loop().
[15:59:09][VV][scheduler:152]: Running interval 'update' with interval=1000 last_execution=284574 (now=286510)

Error from HA:

Logger: homeassistant.components.esphome
Source: components/esphome/__init__.py:197
Integration: ESPHome (documentation, issues)
First occurred: 2:13:20 PM (216 occurrences)
Last logged: 4:02:21 PM

Error getting initial data for 192.x.x.154: Timeout while waiting for API response!

Additional information and things you've tried:

WHAT I ' VE TRIED:

  1. show sensor values to the display. All values are showed properly and are updated on oled display, but their values are not uploaded to HA. HA marked them as unavailable
  2. print only static text to the display - hello world. All sensor values are not uploaded to HA. HA marked them as unavailable
  3. I tried to display not values from attached sensors but read their values as a sensors of a homeassistant platform and then print them to display. Same behaviour, values of sensors are not uploaded to HA. HA marked them as unavailable. And display shows nand values.
  4. I tried to use i2c another bus (esp32 has a two of them), same issue
  5. I tried to use another dev board - esp32 - same issue
  6. I tried to attach display to another esp32 display, without any sensors and read values from HA as a homeassistant platform sensors
  7. Also tried do change board type to esp32dev

I will be thankful for any advice.

pasiz commented 3 years ago

Have you tried supply stable 3.3v directly to board. Seems like power issue in first sight...

bea30 commented 3 years ago

Thank you for your reply. No I did not try 3.3v power source. I am using 5v/2A power supply. I ve tried multiple 5v adapters with max 2A output. Now I tried stronger adapter - 5v-3A with same behaviour.

So I think that it is not related to power isssue. 2A as a max output seems to me as a sufficient power source for only esp32 and one display. There is a same behaviour as when was multiple sensors connected with display to esp. Now as you can see in yaml I am using only a display connected to esp. Also I tried to powering up a display with 5v - (VCC).

bea30 commented 3 years ago

Now I tried a another esp32 board - wroom-32u with external wifi antena connected with display. https://www.tme.eu/sk/details/esp32-devkitc-32u/supravy-na-prenos-dat/espressif/?brutto=1&gclid=EAIaIQobChMIzPuSl6uH7wIVibLVCh0VZwToEAQYASABEgJIDfD_BwE

Same issue. It cant retreive values from HA if a display component(ssd1327_i2c) is defined. Same logs output.

pasiz commented 3 years ago

Thank you for your reply. No I did not try 3.3v power source. I am using 5v/2A power supply. I ve tried multiple 5v adapters with max 2A output. Now I tried stronger adapter - 5v-3A with same behaviour.

So I think that it is not related to power isssue. 2A as a max output seems to me as a sufficient power source for only esp32 and one display. There is a same behaviour as when was multiple sensors connected with display to esp. Now as you can see in yaml I am using only a display connected to esp. Also I tried to powering up a display with 5v - (VCC).

ESP32 is powered with 3.3v. If your internal regulator is not sufficient to provide enough 3.3v power, it would escalate like the behavior you described.

bea30 commented 3 years ago

I thought that internal voltage regulator is not used for the display powering if the displays vcc port is connected to a 5V esp port. So behaviour of the esp board should be the same if the display is connected or not.

Ok then because I dont have any 3.3V power supply I made a another test. Display was powered up from one 5v/2a (vcc, gnd) power supply and esp board was powered up from another power suply 5v/2a through a usb port.

Is this kind of test enought to exclude a problem with internal regulator?

Thank you

pasiz commented 3 years ago

Okay, understanded.

For some reason, like your logs say: [15:58:34][V][app:081]: A component took a long time in a loop() cycle (1.84 s). [15:58:34][V][app:082]: Components should block for at most 20-30ms in loop().

So i suggest there is something wrong, in the display, or connection, thus blocking the mainloop.

Do you see those lines if you just draw something in the display without parsing any data. Have you tried different bus options on the display?

pasiz commented 3 years ago

Also try without very_verbose logging, that is causing often troubles on esp devices as stated on documentation.

https://esphome.io/components/logger.html

bea30 commented 3 years ago

Okay, understanded.

For some reason, like your logs say: [15:58:34][V][app:081]: A component took a long time in a loop() cycle (1.84 s). [15:58:34][V][app:082]: Components should block for at most 20-30ms in loop().

So i suggest there is something wrong, in the display, or connection, thus blocking the mainloop.

No matter what is on screen. Same behaviour spotted if there is only static text or values of the attached sensors (if there some). It is able to display values of attached sensors but as I described in original post after that sensors are marked as unavailable in HA. In this scenario there is also same verbose lines:

[15:58:34][V][app:081]: A component took a long time in a loop() cycle (1.84 s).
[15:58:34][V][app:082]: Components should block for at most 20-30ms in loop().

Do you see those lines if you just draw something in the display without parsing any data. Have you tried different bus options on the display?

No, but I tried different bus on ESP32. I think that this is bug because when I wrote a simple peace of code aimed to test using wifi and display at the same time it works without any problem. The main purpose of the code was connect to wifi, try GET to some url and print out to screen IP adress. Displaying values was in for cycle, and printed out with changing possition from top to bottom in one loop() cycle.

So according to this observation I think that oled screen is OK. Also standard examples from arduinoIDE for ssd1327 driver works.

Also try without very_verbose logging, that is causing often troubles on esp devices as stated on documentation.

https://esphome.io/components/logger.html

That high logger severity was enabled only for a troubleshooting purposes. My standard logger level is WARNING with a same problem. I tried VERY_VERBOSE to gain all possible logs.

pasiz commented 3 years ago

Sorry to ask one more time stupid questions, but you have home assistant sensors that are marked as internal, is that purposeful?

bea30 commented 3 years ago

I am not consider any question as stupid. Every question or pointing me to the right way to resolve this issue is highly appreciated. Esspecialy if the problem may be from a category of PEBKAC 🙂

Sensors from this published config are purposely marked as internal because this values are get from HA and should be only displayed on the oled screen and not send back to HA.

On the other esp32 (where are sensors connected) are not configured as internal and their values are sent and stored in HA.

My first intend was add a display to a esp32 (device A) in living room that can permanently showing air quality values without needed to looking on phone or computer. But when I added a display , printed out values of sensors I noticed that their values are not transmitted to HA.

So configuration from this esp32 (device b) is my another try to make device that can show values of air quality because on the first device it is not working. Now with architecture that all values will be get from HA (but source of values is device A). But it is also not working.

pasiz commented 3 years ago

Could you try not to render special characters (degree signs, etc) while debugging. If that makes no difference, then could you provide i2c VV log.

bea30 commented 3 years ago

I found a problem.

LOGS:

[15:56:09][C][ssd1327_i2c:023]: I2C SSD1327
[15:56:09][C][ssd1327_i2c:023]:   Rotations: 0 °
[15:56:09][C][ssd1327_i2c:023]:   Dimensions: 128px x 128px
[15:56:09][C][ssd1327_i2c:024]:   Address: 0x3C
[15:56:09][C][ssd1327_i2c:025]:   Model: SSD1327 128x128
[15:56:09][C][ssd1327_i2c:027]:   Update Interval: 1.0s

I noticed in my logs that there is a for some reason update interval for a display set to 1s and according to documentation default value should be 5s and I did not change this value or explicitly defined in my configuration.

https://esphome.io/components/display/ssd1327.html?highlight=ssd1327

update_interval (Optional, Time): The interval to re-draw the screen. Defaults to 5s.

So I tried to set update_interval to 5s explicitly and communication with HA started to work. Now esp32 is able to to get values from HA.

Here is my working configuration:

...
i2c:
 - id: bus_a
   sda: 21
#oranzovy 22, zlty 21
   scl: 22 
   scan: True
#   frequency: 200kHz

sensor:
  - platform: homeassistant
    id: temp
    entity_id: sensor.spalna_bme_teplota
    internal: true

  - platform: homeassistant
    id: humidity
    entity_id: sensor.spalna_bme_vlhkost
    internal: true

  - platform: homeassistant
    id: co2
    entity_id: sensor.mh_z19_co2
    internal: true

font:
  - file: "fonts/ComicSansMS3.ttf"
    id: my_font
    size: 20
  - file: "fonts/ComicSansMS3.ttf"
    id: small
    size: 18

display:
  - platform: ssd1327_i2c
    i2c_id: bus_a
    model: "SSD1327 128x128"
    #reset_pin: 
    address: 0x3C
    update_interval: 5s
    lambda: |-
      if (id(temp).has_state()) {
        it.printf(0, 0, id(my_font), "T: %.1fC°", id(temp).state);
      }
      if (id(humidity).has_state()) {
        it.printf(0, 21, id(my_font), "H: %.1f %", id(humidity).state);
      }
      if (id(co2).has_state()) {
        it.printf(0, 41, id(my_font), "CO: %.0f ppm", id(co2).state);
      }

There are still persists verbose records about a component took a long time in a loop cycle.

LOGS:

13:48:58][VV][scheduler:152]: Running interval 'update' with interval=5000 last_execution=431547 (now=436548)
[13:49:00][V][app:081]: A component took a long time in a loop() cycle (1.82 s).
[13:49:00][V][app:082]: Components should block for at most 20-30ms in loop().
[13:49:01][VV][api.service:220]: on_ping_request: PingRequest {}
[13:49:01][VV][api.service:032]: send_ping_response: PingResponse {}
[13:49:02][VV][api.service:326]: on_home_assistant_state_response: HomeAssistantStateResponse {
  entity_id: 'sensor.mh_z19_co2'
  state: '1701'
}
[13:49:02][D][homeassistant.sensor:019]: 'sensor.mh_z19_co2': Got state 1701.00
[13:49:02][V][sensor:013]: 'co2': Received new state 1701.000000
[13:49:02][D][sensor:092]: 'co2': Sending state 1701.00000  with 1 decimals of accuracy
[13:49:03][VV][scheduler:152]: Running interval 'update' with interval=5000 last_execution=436547 (now=441549)
[13:49:05][V][app:081]: A component took a long time in a loop() cycle (1.82 s).
[13:49:05][V][app:082]: Components should block for at most 20-30ms in loop().
[13:49:05][VV][api.service:326]: on_home_assistant_state_response: HomeAssistantStateResponse {
  entity_id: 'sensor.spalna_bme_teplota'
  state: '22.0'
}
[13:49:05][D][homeassistant.sensor:019]: 'sensor.spalna_bme_teplota': Got state 22.00
[13:49:05][V][sensor:013]: 'temp': Received new state 22.000000
[13:49:05][D][sensor:092]: 'temp': Sending state 22.00000  with 1 decimals of accuracy
[13:49:06][VV][api.service:220]: on_ping_request: PingRequest {}
[13:49:06][VV][api.service:032]: send_ping_response: PingResponse {}
[13:49:07][VV][api.service:220]: on_ping_request: PingRequest {}
[13:49:07][VV][api.service:032]: send_ping_response: PingResponse {}
[13:49:08][VV][scheduler:152]: Running interval 'update' with interval=5000 last_execution=441547 (now=446548)
[13:49:10][V][app:081]: A component took a long time in a loop() cycle (1.82 s).
[13:49:10][V][app:082]: Components should block for at most 20-30ms in loop().
[13:49:11][VV][api.service:220]: on_ping_request: PingRequest {}
[13:49:11][VV][api.service:032]: send_ping_response: PingResponse {}
[13:49:13][VV][scheduler:152]: Running interval 'update' with interval=5000 last_execution=446547 (now=451552)
[13:49:15][VV][api.service:326]: on_home_assistant_state_response: HomeAssistantStateResponse {
  entity_id: 'sensor.spalna_bme_teplota'
  state: '22.1'
}
[13:49:15][D][homeassistant.sensor:019]: 'sensor.spalna_bme_teplota': Got state 22.10
[13:49:15][V][sensor:013]: 'temp': Received new state 22.100000
[13:49:15][D][sensor:092]: 'temp': Sending state 22.10000  with 1 decimals of accuracy
[13:49:15][VV][api.service:326]: on_home_assistant_state_response: HomeAssistantStateResponse {
  entity_id: 'sensor.spalna_bme_vlhkost'
  state: '41.2'
}
[13:49:15][D][homeassistant.sensor:019]: 'sensor.spalna_bme_vlhkost': Got state 41.20
[13:49:15][V][sensor:013]: 'humidity': Received new state 41.200001
[13:49:15][D][sensor:092]: 'humidity': Sending state 41.20000  with 1 decimals of accuracy
[13:49:15][V][app:081]: A component took a long time in a loop() cycle (1.87 s).
[13:49:15][V][app:082]: Components should block for at most 20-30ms in loop().
[13:49:17][VV][api.service:220]: on_ping_request: PingRequest {}
[13:49:17][VV][api.service:032]: send_ping_response: PingResponse {}
[13:49:18][VV][scheduler:152]: Running interval 'update' with interval=5000 last_execution=451547 (now=456547)
[13:49:20][V][app:081]: A component took a long time in a loop() cycle (1.80 s).
[13:49:20][V][app:082]: Components should block for at most 20-30ms in loop().
[13:49:22][VV][api.service:220]: on_ping_request: PingRequest {}
[13:49:22][VV][api.service:032]: send_ping_response: PingResponse {}

Question is why is a default value of update_interval set to 1s when a documentation says it is 5s. Apparently 1s interval breaks network communication between esp32 and HA and I also noticed that sometimes breaks wifi network connection as well.

With a explicitly defined update_interval to 5s display component works also on a device A (mentioned a few comments above) where are attached sensors (BME680, MHZ-19) and renders values straight from them. Their values are also transmitted to HA without any problem.

pasiz Thank you for pushing me to making new tests.