search

esphome / issues

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

esp32_ble error "Unhandled type: advType: 0x08" #930

Closed borpin closed 4 years ago

borpin commented 4 years ago

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

Hass.io - Home Assistant 0.102.3 DietPi VM Using add-on Using esphome-dev

ESP (ESP32/ESP8266, Board/Sonoff):

WEMOS D32 Affected component:

esp32_ble_tracker

Description of problem: No data just "[08:47:30][V][esp32_ble_tracker:420]: Unhandled type: advType: 0x08" as an error

I am also seeing WiFi problems but I do not know if that is related.

Problem-relevant YAML-configuration entries:

substitutions:
  device_name: esp32_ble
  display_name: "BLE Temp & Presence"
  name: esp32_ble
  platform: ESP32
  board: lolin_d32
  cc_devicename: "BLE Temp & Presence"
  static_ip: 192.168.7.63
  log_level: VERBOSE
  arduino_ver: recommended

<<: !include common/esphome.yaml
<<: !include common/wifi.yaml
<<: !include common/mqtt.yaml

esp32_ble_tracker:

binary_sensor:
 - platform: ble_presence
   mac_address: 38:80:df:98:f1:f2
   name: Brian_ESP32

sensor:
  - platform: xiaomi_lywsdcgq
    mac_address: 4c:65:a8:d8:de:98
    temperature:
      name: "Xiaomi Temperature 98"
      state_topic: emon/xiaomi4c:65:a8:d8:de:98/temperature
      filters:
        - throttle: 90s
        - offset: -0.65
        - sliding_window_moving_average:
            window_size: 11
            send_every: 1
    humidity:
      name: "Xiaomi Humidity 98"
      state_topic: emon/xiaomi4c:65:a8:d8:de:98/humidity
      filters:
        - sliding_window_moving_average:
            window_size: 5
            send_every: 1
        - throttle: 90s
    battery_level:
      name: "Xiaomi Battery 98"
      state_topic: emon/xiaomi4c:65:a8:d8:de:98/battery

Logs (if applicable):

Successful boot log (from Hass.io addon)

INFO Reading configuration /config/esphome/esp32_ble.yaml...
INFO Starting log output from 192.168.7.63 using esphome API
INFO Connecting to 192.168.7.63:6053 (192.168.7.63)
INFO Successfully connected to 192.168.7.63
[08:52:27][I][app:100]: ESPHome version 1.15.0-dev compiled on Dec 16 2019, 08:35:27
[08:52:27][C][wifi:415]: WiFi:
[08:52:27][C][wifi:283]:   SSID: [redacted]
[08:52:27][C][wifi:284]:   IP Address: 192.168.7.63
[08:52:27][C][wifi:286]:   BSSID: [redacted]
[08:52:27][C][wifi:287]:   Hostname: 'esp32_ble'
[08:52:27][C][wifi:291]:   Signal strength: -62 dB ▂▄▆█
[08:52:27][V][wifi:293]:   Priority: -1.0
[08:52:27][C][wifi:295]:   Channel: 11
[08:52:27][C][wifi:296]:   Subnet: 255.255.255.0
[08:52:27][C][wifi:297]:   Gateway: 192.168.7.25
[08:52:27][C][wifi:298]:   DNS1: 192.168.7.29
[08:52:27][C][wifi:299]:   DNS2: 0.0.0.0
[08:52:27][C][logger:175]: Logger:
[08:52:27][C][logger:176]:   Level: VERBOSE
[08:52:27][C][logger:177]:   Log Baud Rate: 115200
[08:52:27][C][logger:178]:   Hardware UART: UART0
[08:52:27][C][esp32_ble_tracker:445]: BLE Tracker:
[08:52:27][C][esp32_ble_tracker:446]:   Scan Duration: 300 s
[08:52:27][C][esp32_ble_tracker:447]:   Scan Interval: 320.0 ms
[08:52:27][C][esp32_ble_tracker:448]:   Scan Window: 30.0 ms
[08:52:27][C][esp32_ble_tracker:449]:   Scan Type: ACTIVE
[08:52:27][C][ble_presence:011]: BLE Presence 'Brian_ESP32'
[08:52:27][C][xiaomi_lywsdcgq:012]: Xiaomi LYWSDCGQ
[08:52:27][C][xiaomi_lywsdcgq:013]:   Temperature 'Xiaomi Temperature 98'
[08:52:27][C][xiaomi_lywsdcgq:013]:     Unit of Measurement: '°C'
[08:52:27][C][xiaomi_lywsdcgq:013]:     Accuracy Decimals: 1
[08:52:27][C][xiaomi_lywsdcgq:013]:     Icon: 'mdi:thermometer'
[08:52:27][C][xiaomi_lywsdcgq:014]:   Humidity 'Xiaomi Humidity 98'
[08:52:27][C][xiaomi_lywsdcgq:014]:     Unit of Measurement: '%'
[08:52:27][C][xiaomi_lywsdcgq:014]:     Accuracy Decimals: 1
[08:52:27][C][xiaomi_lywsdcgq:014]:     Icon: 'mdi:water-percent'
[08:52:27][C][xiaomi_lywsdcgq:015]:   Battery Level 'Xiaomi Battery 98'
[08:52:27][C][xiaomi_lywsdcgq:015]:     Unit of Measurement: '%'
[08:52:27][C][xiaomi_lywsdcgq:015]:     Accuracy Decimals: 0
[08:52:27][C][xiaomi_lywsdcgq:015]:     Icon: 'mdi:battery'
[08:52:27][C][ota:029]: Over-The-Air Updates:
[08:52:27][C][ota:030]:   Address: 192.168.7.63:3232
[08:52:27][W][ota:036]: Last Boot was an unhandled reset, will proceed to safe mode in 8 restarts
[08:52:27][C][api:095]: API Server:
[08:52:27][C][api:096]:   Address: 192.168.7.63:6053
[08:52:27][C][mqtt:051]: MQTT:
[08:52:27][C][mqtt:053]:   Server Address: 192.168.7.50:1883 (192.168.7.50)
[08:52:27][C][mqtt:054]:   Username: [redacted]
[08:52:27][C][mqtt:055]:   Client ID: [redacted]
[08:52:27][C][mqtt:060]:   Topic Prefix: 'esp32_ble'
[08:52:27][C][mqtt:062]:   Log Topic: 'esp32_ble/debug'
[08:52:27][C][mqtt:065]:   Availability: 'esp32_ble/status'
[08:52:27][C][mqtt.binary_sensor:018]: MQTT Binary Sensor 'Brian_ESP32':
[08:52:27][C][mqtt.binary_sensor:019]:   State Topic: 'esp32_ble/binary_sensor/brian_esp32/state'
[08:52:27][C][mqtt.sensor:024]: MQTT Sensor 'Xiaomi Temperature 98':
[08:52:27][C][mqtt.sensor:028]:   State Topic: 'emon/xiaomi4c:65:a8:d8:de:98/temperature'
[08:52:27][C][mqtt.sensor:024]: MQTT Sensor 'Xiaomi Humidity 98':
[08:52:27][C][mqtt.sensor:028]:   State Topic: 'emon/xiaomi4c:65:a8:d8:de:98/humidity'
[08:52:27][C][mqtt.sensor:024]: MQTT Sensor 'Xiaomi Battery 98':
[08:52:27][C][mqtt.sensor:028]:   State Topic: 'emon/xiaomi4c:65:a8:d8:de:98/battery'
[08:52:28][V][esp32_ble_tracker:420]: Unhandled type: advType: 0x08
[08:52:29][V][esp32_ble_tracker:420]: Unhandled type: advType: 0x08
[08:52:31][V][esp32_ble_tracker:420]: Unhandled type: advType: 0x08
[08:52:31][V][esp32_ble_tracker:420]: Unhandled type: advType: 0x08
[08:52:32][V][esp32_ble_tracker:420]: Unhandled type: advType: 0x08
[08:52:34][V][esp32_ble_tracker:420]: Unhandled type: advType: 0x08
[08:52:36][V][esp32_ble_tracker:420]: Unhandled type: advType: 0x08
[08:52:36][V][esp32_ble_tracker:420]: Unhandled type: advType: 0x08
[08:52:38][V][esp32_ble_tracker:420]: Unhandled type: advType: 0x08
[08:52:38][V][esp32_ble_tracker:420]: Unhandled type: advType: 0x08
[08:52:40][V][esp32_ble_tracker:420]: Unhandled type: advType: 0x08
[08:52:41][V][esp32_ble_tracker:420]: Unhandled type: advType: 0x08
[08:52:41][V][esp32_ble_tracker:420]: Unhandled type: advType: 0x08
[08:52:43][V][esp32_ble_tracker:420]: Unhandled type: advType: 0x08
[08:52:44][V][esp32_ble_tracker:420]: Unhandled type: advType: 0x08
[08:52:45][V][esp32_ble_tracker:420]: Unhandled type: advType: 0x08
[08:52:46][V][esp32_ble_tracker:420]: Unhandled type: advType: 0x08
[08:52:47][V][esp32_ble_tracker:420]: Unhandled type: advType: 0x08
[08:52:48][I][ota:046]: Boot seems successful, resetting boot loop counter.
[08:52:48][V][esp32_ble_tracker:420]: Unhandled type: advType: 0x08

Error log (when Wi-Fi playing up from esphome-flasher serial output.

[08:47:26][D][WiFiGeneric.cpp:337] _eventCallback(): Event: 1 - SCAN_DONE
[08:47:26][V][wifi_esp32:307]: Event: WiFi Scan Done status=0 number=5 scan_id=128
[08:47:26][D][wifi:319]: Found networks:
[08:47:26][I][wifi:365]: - 'ZyXEL WAP3205 v3' (8C:59:73:77:64:59) ▂▄▆█
[08:47:26][D][wifi:366]:     Channel: 4
[08:47:26][D][wifi:367]:     RSSI: -53 dB
[08:47:26][I][wifi:365]: - 'home123' (B4:FB:E4:97:8D:5F) ▂▄▆█
[08:47:26][D][wifi:366]:     Channel: 11
[08:47:26][D][wifi:367]:     RSSI: -65 dB
[08:47:26][D][wifi:369]: - 'IOTWLAN_IoT' (C6:FB:E4:97:8D:5F) ▂▄▆█
[08:47:26][D][wifi:369]: - 'BTHub6-3GHM' (B6:FB:E4:97:8D:5F) ▂▄▆█
[08:47:26][D][wifi:369]: - 'BTWifi-with-FON' (58:90:43:3F:82:2A) ▂▄▆█
[08:47:26][I][wifi:193]: WiFi Connecting to 'ZyXEL WAP3205 v3'...
[08:47:26][V][wifi:195]: Connection Params:
[08:47:26][V][wifi:196]:   SSID: 'ZyXEL WAP3205 v3'
[08:47:26][V][wifi:199]:   BSSID: 8C:59:73:77:64:59
[08:47:26][V][wifi:203]:   Password: 'UQKAA49947'
[08:47:26][V][wifi:205]:   Channel: 4
[08:47:26][V][wifi:213]:   Manual IP: Static IP=192.168.7.63 Gateway=192.168.7.25 Subnet=255.255.255.0 DNS1=192.168.7.29 DNS2=0.0.0.0
[08:47:26][V][wifi:217]:   Hidden: NO
[08:47:26][V][esp32_ble_tracker:420]: Unhandled type: advType: 0x08
[08:47:26][V][esp-idf:000]: I (3778) wifi: n:4 0, o:1 0, ap:255 255, sta:4 0, prof:1
[08:47:27][V][esp-idf:000]: I (4517) wifi: state: init -> auth (b0)
[08:47:27][V][esp-idf:000]: I (4525) wifi: state: auth -> assoc (0)
[08:47:27][D][esp32_ble_tracker:459]: Found device 4C:65:A8:DC:8A:9B RSSI=-79
[08:47:27][D][esp32_ble_tracker:480]:   Address Type: PUBLIC
[08:47:27][D][esp-idf:000]: I (4534) wifi: state: assoc -> r
[08:47:27][V][esp-idf:000]: I (4534) wifi: state: assoc -> run (10)
[08:47:27][V][esp-idf:000]: I (4557) wifi: connected with ZyXEL WAP3205 v3, channel 4, BW20
[08:47:27][V][esp-idf:000]: I (4558) wifi: pm start, type: 1
[08:47:27]
[08:47:29][D][esp32_ble_tracker:459]: Found device 4C:65:A8:D8:E0:0C RSSI=-72
[08:47:29][D][esp32_ble_tracker:480]:   Address Type: PUBLIC
[08:47:29][D][esp32_ble_tracker:482]:   Name: 'MJ_HT_V1'
[08:47:30][V][esp32_ble_tracker:420]: Unhandled type: advType: 0x08
[08:47:32][V][esp32_ble_tracker:420]: Unhandled type: advType: 0x08
[08:47:34][V][esp32_ble_tracker:420]: Unhandled type: advType: 0x08
[08:47:35][V][esp32_ble_tracker:420]: Unhandled type: advType: 0x08
[08:47:38][V][esp32_ble_tracker:420]: Unhandled type: advType: 0x08
[08:47:41][D][esp32_ble_tracker:459]: Found device 4C:65:A8:D9:59:1A RSSI=-82
[08:47:41][D][esp32_ble_tracker:480]:   Address Type: PUBLIC
[08:47:41][D][esp32_ble_tracker:482]:   Name: 'MJ_HT_V1'
[08:47:41][V][esp32_ble_tracker:420]: Unhandled type: advType: 0x08
[08:47:44][V][esp32_ble_tracker:420]: Unhandled type: advType: 0x08
[08:47:47][V][esp32_ble_tracker:420]: Unhandled type: advType: 0x08
[08:47:47][V][esp32_ble_tracker:420]: Unhandled type: advType: 0x08
[08:47:49][V][esp32_ble_tracker:420]: Unhandled type: advType: 0x08
[08:47:51][V][esp32_ble_tracker:420]: Unhandled type: advType: 0x08
[08:47:52][V][esp32_ble_tracker:420]: Unhandled type: advType: 0x08
[08:47:55][V][esp32_ble_tracker:420]: Unhandled type: advType: 0x08
[08:47:56][W][wifi:445]: Timeout while connecting to WiFi.
[08:47:56][D][WiFiGeneric.cpp:337] _eventCallback(): Event: 7 - STA_GOT_IP
[08:47:56][D][WiFiGeneric.cpp:381] _eventCallback(): STA IP: 192.168.7.63, MASK: 255.255.255.0, GW: 192.168.7.25
[08:47:56][V][wifi:193]: WiFi Connecting to 'ZyXEL WAP3205 v3'...[I][wifi:193]: WiFi Connecting to 'ZyXEL WAP3205 v3'...
[08:47:56][V][wifi:195]: Connection Params:
[08:47:56][V][wifi:196]:   SSID: 'ZyXEL WAP3205 v3'
[08:47:56][V][wifi:199]:   BSSID: 8C:59:73:77:64:59
[08:47:56][V][wifi:203]:   Password: 'UQKAA49947'
[08:47:56][V][wifi:205]:   Channel: 4
[08:47:56][V][wifi:213]:   Manual IP: Static IP=192.168.7.63 Gateway=192.168.7.25 Subnet=255.255.255.0 DNS1=192.168.7.29 DNS2=0.0.0.0
[08:47:56][V][wifi:217]:   Hidden: NO
[08:47:56]
[08:47:56][D][WiFiGeneric.cpp:337] _eventCallback(): Event: 7 - STA_GOT_IP
[08:47:56][D][WiFiGeneric.cpp:381] _eventCallback(): STA IP: 192.168.7.63, MASK: 255.255.255.0, GW: 192.168.7.25
[08:47:56][V][esp-idf:000]: I (33653) wifi: n:4 0, o:4 0, ap:255 255, sta:4 0, prof:1[08:47:56][V][esp-idf:000]: I (33653) wifi: n:4 0, o:4 0, ap:255 255, sta:4 0, prof:1
[08:47:56][V][wifi_esp32:111]: Stopping DHCP client failed! 20485
[08:47:56][D][WiFiGeneric.cpp:337] _eventCallback(): Event: 5 - STA_DISCONNECTED
[08:47:56][W][WiFiGeneric.cpp:353] _eventCallback(): Reason: 8 - ASSOC_LEAVE
[08:47:56][W][wifi_esp32:336]: Event: Disconnected ssid='ZyXEL WAP3205 v3' bssid=8C:
[08:47:56][C][wifi:283]:   SSID: ''8C:59:73:77:64:59 reason='Association Leave'[C][wifi:283]:   SSID: ''
[08:47:56][C][wifi:284]:   IP Address: 192.168.7.63
[08:47:56][C][wifi:286]:   BSSID: 00:00:00:00:00:00
[08:47:56][C][wifi:287]:   Hostname: 'esp32_ble'
[08:47:56]
[08:47:56][V][esp-idf:000]: W (33724) wifi: Haven't to connect to a suitable AP now!
[08:47:56][C][wifi:291]:   Signal strength: 0 dB ▂▄▆█
[08:47:56][V][wifi:293]:   Priority: -1.0
[08:47:56][C][wifi:295]:   Channel: 4
[08:47:56][C][wifi:296]:   Subnet: 255.255.255.0
[08:47:56][C][wifi:297]:   Gateway: 192.168.7.25
[08:47:56][C][wifi:298]:   DNS1: 192.168.7.29
[08:47:56][C][wifi:299]:   DNS2: 0.0.0.0
[08:47:56][W][wifi:100]: WiFi Connection lost... Reconnecting...
[08:47:56][V][esp-idf:000]: I (33876) wifi: n:4 0, o:4 0, ap:255 255, sta:4 0, prof:1
[08:47:56][V][esp-idf:000]: I (33877) wifi: state: init -> auth (b0)
[08:47:56][V][esp-idf:000]: I (33882) wifi: state: auth -> assoc (0)
[08:47:56][V][esp-idf:000]: I (33891) wifi: state: assoc -> run (10)
[08:47:56][V][esp32_ble_tracker:420]: Unhandled type: advType: 0x08
[08:47:56][V][esp-idf:000]: I (33936) wifi: connected with ZyXEL WAP3205 v3, channel 4, BW20
[08:47:56][V][esp-idf:000]: I (33936) wifi: pm start, type: 1
[08:47:56]
[08:48:00][V][esp32_ble_tracker:420]: Unhandled type: advType: 0x08
[08:48:00][V][esp32_ble_tracker:420]: Unhandled type: advType: 0x08
[08:48:01][D][wifi:304]: Starting scan...
[08:48:01][D][WiFiGeneric.cpp:337] _eventCallback(): Event: 7 - STA_GOT_IP
[08:48:02][D][WiFiGeneric.cpp:381] _eventCallback(): STA IP: 192.168.7.63, MASK: 255.255.255.0, GW: 192.168.7.25
[08:48:02][V][esp32_ble_tracker:420]: Unhandled type: advType: 0x08
[08:48:02][D][esp32_ble_tracker:459]: Found device 4C:65:A8:D8:E0:D8 RSSI=-67
[08:48:02][D][esp32_ble_tracker:480]:   Address Type: PUBLIC
[08:48:02][D][esp32_ble_tracker:482]:   Name: 'MJ_HT_V1'
[08:48:04][V][esp32_ble_tracker:420]: Unhandled type: advType: 0x08
[08:48:06][V][esp32_ble_tracker:420]: Unhandled type: advType: 0x08
[08:48:06][V][esp32_ble_tracker:420]: Unhandled type: advType: 0x08
[08:48:06][V][esp32_ble_tracker:420]: Unhandled type: advType: 0x08
[08:48:09][D][esp32_ble_tracker:459]: Found device 4C:65:A8:D8:DE:98 RSSI=-83
[08:48:09][D][esp32_ble_tracker:480]:   Address Type: PUBLIC
[08:48:09][D][esp32_ble_tracker:482]:   Name: 'MJ_HT_V1'
[08:48:10][V][esp32_ble_tracker:420]: Unhandled type: advType: 0x08
[08:48:12][V][esp32_ble_tracker:420]: Unhandled type: advType: 0x08
[08:48:14][D][esp32_ble_tracker:459]: Found device 79:09:51:B6:D2:F2 RSSI=-85
[08:48:14][D][esp32_ble_tracker:480]:   Address Type: RANDOM
[08:48:14][V][esp32_ble_tracker:420]: Unhandled type: advType: 0x08
[08:48:15][V][esp32_ble_tracker:420]: Unhandled type: advType: 0x08
[08:48:16][V][esp32_ble_tracker:420]: Unhandled type: advType: 0x08
[08:48:20][V][esp32_ble_tracker:420]: Unhandled type: advType: 0x08
[08:48:21][V][esp32_ble_tracker:420]: Unhandled type: advType: 0x08
[08:48:22][V][esp32_ble_tracker:420]: Unhandled type: advType: 0x08
[08:48:25][V][esp32_ble_tracker:420]: Unhandled type: advType: 0x08
[08:48:26][V][esp32_ble_tracker:420]: Unhandled type: advType: 0x08
[08:48:26][D][esp32_ble_tracker:459]: Found device 56:88:A7:CB:B6:0E RSSI=-90
[08:48:26][D][esp32_ble_tracker:480]:   Address Type: RANDOM
[08:48:26][V][esp32_ble_tracker:420]: Unhandled type: advType: 0x08
[08:48:30][V][esp32_ble_tracker:420]: Unhandled type: advType: 0x08
[08:48:31][E][wifi:312]: Scan timeout!
[08:48:31][V][wifi_esp32:307]: Event: WiFi Scan Done status=0 number=5 scan_id=129
[08:48:31][D][WiFiGeneric.cpp:337] _eventCallback(): Event: 1 - SCAN_DONE
[08:48:32][V][wifi_esp32:307]: Event: WiFi Scan Done statType: 0x08[V][esp32_ble_tracker:420]: Unhandled type: advType: 0x08
[08:48:35][V][esp32_ble_tracker:420]: Unhandled type: advType: 0x08
[08:48:35][V][esp32_ble_tracker:420]: Unhandled type: advType: 0x08
[08:48:36][D][wifi:304]: Starting scan...
[08:48:36]
[08:48:36][D][WiFiGeneric.cpp:337] _eventCallback(): Event: 1 - SCAN_DONE
[08:48:37][V][wifi_esp32:307]: Event: WiFi Scan Done status=e: 0x08[V][esp32_ble_tracker:420]: Unhandled type: advType: 0x08
[08:48:42][V][esp32_ble_tracker:420]: Unhandled type: advType: 0x08
[08:48:43][V][esp32_ble_tracker:420]: Unhandled type: advType: 0x08
[08:48:44][D][esp32_ble_tracker:459]: Found device 75:3F:9A:7E:DE:B9 RSSI=-96
[08:48:44][D][esp32_ble_tracker:480]:   Address Type: RANDOM
[08:48:46][V][esp32_ble_tracker:420]: Unhandled type: advType: 0x08
[08:48:47][V][esp32_ble_tracker:420]: Unhandled type: advType: 0x08
[08:48:48][V][esp32_ble_tracker:420]: Unhandled type: advType: 0x08
[08:48:50][V][esp32_ble_tracker:420]: Unhandled type: advType: 0x08
[08:48:54][V][esp32_ble_tracker:420]: Unhandled type: advType: 0x08
[08:48:55][V][esp32_ble_tracker:420]: Unhandled type: advType: 0x08
[08:48:55][V][esp32_ble_tracker:420]: Unhandled type: advType: 0x08
[08:48:57][V][esp32_ble_tracker:420]: Unhandled type: advType: 0x08
[08:48:58][V][esp32_ble_tracker:420]: Unhandled type: advType: 0x08
[08:49:03][V][esp32_ble_tracker:420]: Unhandled type: advType: 0x08
[08:49:05][V][esp32_ble_tracker:420]: Unhandled type: advType: 0x08
[08:49:05][V][esp32_ble_tracker:420]: Unhandled type: advType: 0x08
[08:49:06][E][wifi:312]: Scan timeout!
[08:49:06]
[08:49:06][D][WiFiGeneric.cpp:337] _eventCallback(): Event: 1 - SCAN_DONE
[08:49:06][V][wifi_esp32:307]: Event: WiFi Scan Done status=0 number=5 scan_id=130

Additional information and things you've tried: Tried different Ardiuno framework versions.

It used to work, then one of the updates to either esphome or the framework broke it.

stale[bot] commented 4 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.

OttoWinter commented 4 years ago

Not really an issue (it's intentionally just a verbose log message).

It just means that the BLE device sent more data with a different advertisement field type (there are a lot of them, ESPHome doesn't handle all of them because no integration needs them yet)