search

home-assistant / supervisor

:house_with_garden: Home Assistant Supervisor
https://home-assistant.io/hassio/
Apache License 2.0
1.74k stars 638 forks source link

BLE devices generate system errors #964

Closed tiagofreire-pt closed 5 years ago

tiagofreire-pt commented 5 years ago

Home Assistant release with the issue:

0.89.2

Operating environment (HassOS/Generic):

Hassio 32bit on Rpi 3B+

Supervisor logs:

019-03-19 18:54:02 DEBUG (SyncWorker_3) [miflora.miflora_poller] Received result for handle 56: 63 2A 33 2E 32 2E 31 2019-03-19 18:54:02 DEBUG (SyncWorker_12) [miflora.miflora_poller] Received result for handle 56: 63 2A 33 2E 32 2E 31 2019-03-19 18:54:02 DEBUG (SyncWorker_12) [homeassistant.components.sensor.miflora] Coentros Battery = 99 2019-03-19 18:54:02 DEBUG (SyncWorker_12) [homeassistant.components.sensor.miflora] Data collected: [99] 2019-03-19 18:54:02 DEBUG (SyncWorker_12) [homeassistant.components.sensor.miflora] Set initial state 2019-03-19 18:54:05 DEBUG (SyncWorker_7) [miflora.miflora_poller] Received result for handle 56: 63 2A 33 2E 32 2E 31 2019-03-19 18:54:05 DEBUG (SyncWorker_7) [homeassistant.components.sensor.miflora] Suculenta Battery = 99 2019-03-19 18:54:05 DEBUG (SyncWorker_7) [homeassistant.components.sensor.miflora] Data collected: [99] 2019-03-19 18:54:05 DEBUG (SyncWorker_7) [homeassistant.components.sensor.miflora] Set initial state 2019-03-19 18:54:05 DEBUG (SyncWorker_5) [miflora.miflora_poller] Received result for handle 56: 63 2A 33 2E 32 2E 31 2019-03-19 18:54:08 DEBUG (SyncWorker_3) [miflora.miflora_poller] Received result for handle 53: D2 00 00 47 00 00 00 33 E7 00 02 3C 00 FB 34 9B 2019-03-19 18:54:08 DEBUG (SyncWorker_3) [homeassistant.components.sensor.miflora] Coentros Moisture = 51 2019-03-19 18:54:08 DEBUG (SyncWorker_14) [miflora.miflora_poller] Using cache (0:00:00.005138 < 0:20:00) 2019-03-19 18:54:08 DEBUG (SyncWorker_3) [homeassistant.components.sensor.miflora] Data collected: [51] 2019-03-19 18:54:08 DEBUG (SyncWorker_14) [homeassistant.components.sensor.miflora] Coentros Light intensity = 71 2019-03-19 18:54:08 DEBUG (SyncWorker_15) [miflora.miflora_poller] Using cache (0:00:00.012262 < 0:20:00) 2019-03-19 18:54:08 DEBUG (SyncWorker_3) [homeassistant.components.sensor.miflora] Set initial state 2019-03-19 18:54:08 DEBUG (SyncWorker_14) [homeassistant.components.sensor.miflora] Data collected: [71] 2019-03-19 18:54:08 DEBUG (SyncWorker_15) [homeassistant.components.sensor.miflora] Coentros Temperature = 21.0 2019-03-19 18:54:08 DEBUG (SyncWorker_1) [miflora.miflora_poller] Using cache (0:00:00.030697 < 0:20:00) 2019-03-19 18:54:08 DEBUG (SyncWorker_14) [homeassistant.components.sensor.miflora] Set initial state 2019-03-19 18:54:08 DEBUG (SyncWorker_15) [homeassistant.components.sensor.miflora] Data collected: [21.0] 2019-03-19 18:54:08 DEBUG (SyncWorker_1) [homeassistant.components.sensor.miflora] Coentros Conductivity = 231 2019-03-19 18:54:08 DEBUG (SyncWorker_15) [homeassistant.components.sensor.miflora] Set initial state 2019-03-19 18:54:08 DEBUG (SyncWorker_1) [homeassistant.components.sensor.miflora] Data collected: [231] 2019-03-19 18:54:08 DEBUG (SyncWorker_1) [homeassistant.components.sensor.miflora] Set initial state 2019-03-19 18:54:08 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.suculenta_temperature is taking over 10 seconds 2019-03-19 18:54:08 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.suculenta_conductivity is taking over 10 seconds 2019-03-19 18:54:08 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.suculenta_moisture is taking over 10 seconds 2019-03-19 18:54:08 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.suculenta_light_intensity is taking over 10 seconds 2019-03-19 18:54:10 INFO (SyncWorker_11) [pygatt.backends.gatttool.gatttool] Starting BLE scan 2019-03-19 18:54:10 ERROR (SyncWorker_11) [pygatt.backends.gatttool.gatttool] Unexpected error when scanning: LE Scan ... 2019-03-19 18:54:10 ERROR (MainThread) [homeassistant.core] Error doing job: Future exception was never retrieved Traceback (most recent call last): File "/usr/local/lib/python3.7/site-packages/pexpect/spawnbase.py", line 166, in read_nonblocking s = os.read(self.child_fd, size) OSError: [Errno 5] I/O error During handling of the above exception, another exception occurred: Traceback (most recent call last): File "/usr/local/lib/python3.7/site-packages/pexpect/expect.py", line 111, in expect_loop incoming = spawn.read_nonblocking(spawn.maxread, timeout) File "/usr/local/lib/python3.7/site-packages/pexpect/pty_spawn.py", line 485, in read_nonblocking return super(spawn, self).read_nonblocking(size) File "/usr/local/lib/python3.7/site-packages/pexpect/spawnbase.py", line 171, in readnonblocking raise EOF('End Of File (EOF). Exception style platform.') pexpect.exceptions.EOF: End Of File (EOF). Exception style platform. During handling of the above exception, another exception occurred: Traceback (most recent call last): File "/usr/local/lib/python3.7/site-packages/pygatt/backends/gatttool/gatttool.py", line 315, in scan scan.expect('foooooo', timeout=timeout) File "/usr/local/lib/python3.7/site-packages/pexpect/spawnbase.py", line 341, in expect timeout, searchwindowsize, async) File "/usr/local/lib/python3.7/site-packages/pexpect/spawnbase.py", line 369, in expect_list return exp.expect_loop(timeout) File "/usr/local/lib/python3.7/site-packages/pexpect/expect.py", line 117, in expect_loop return self.eof(e) File "/usr/local/lib/python3.7/site-packages/pexpect/expect.py", line 63, in eof raise EOF(msg) pexpect.exceptions.EOF: End Of File (EOF). Exception style platform. <pexpect.pty_spawn.spawn object at 0x6d4e73f0> command: /usr/bin/hcitool args: ['/usr/bin/hcitool', '-i', 'hci0', 'lescan'] buffer (last 100 chars): b'' before (last 100 chars): b'LE Scan ...\r\n' after: <class 'pexpect.exceptions.EOF'> match: None match_index: None exitstatus: None flag_eof: True pid: 95 child_fd: 42 closed: False timeout: 30 delimiter: <class 'pexpect.exceptions.EOF'> logfile: None logfile_read: None logfile_send: None maxread: 2000 ignorecase: False searchwindowsize: None delaybeforesend: 0.05 delayafterclose: 0.1 delayafterterminate: 0.1 searcher: searcher_re: 0: re.compile(b'foooooo') During handling of the above exception, another exception occurred: Traceback (most recent call last): File "/usr/local/lib/python3.7/concurrent/futures/thread.py", line 57, in run result = self.fn(*self.args, **self.kwargs) File "/usr/local/lib/python3.7/site-packages/homeassistant/components/device_tracker/bluetooth_le_tracker.py", line 96, in update_ble devs = discover_ble_devices() File "/usr/local/lib/python3.7/site-packages/homeassistant/components/device_tracker/bluetooth_le_tracker.py", line 58, in discover_ble_devices devs = adapter.scan() File "/usr/local/lib/python3.7/site-packages/pygatt/backends/gatttool/gatttool.py", line 326, in scan raise BLEError(message) pygatt.exceptions.BLEError: Unexpected error when scanning: LE Scan ... 2019-03-19 18:54:10 DEBUG (SyncWorker_5) [miflora.miflora_poller] Received result for handle 53: D1 00 00 00 00 00 00 12 2B 00 02 3C 00 FB 34 9B 2019-03-19 18:54:10 DEBUG (SyncWorker_5) [homeassistant.components.sensor.miflora] Suculenta Temperature = 20.9 2019-03-19 18:54:10 DEBUG (SyncWorker_2) [miflora.miflora_poller] Using cache (0:00:00.003998 < 0:20:00) 2019-03-19 18:54:10 DEBUG (SyncWorker_5) [homeassistant.components.sensor.miflora] Data collected: [20.9] 2019-03-19 18:54:10 DEBUG (SyncWorker_2) [homeassistant.components.sensor.miflora] Suculenta Conductivity = 43 2019-03-19 18:54:10 DEBUG (SyncWorker_8) [miflora.miflora_poller] Using cache (0:00:00.009145 < 0:20:00) 2019-03-19 18:54:10 DEBUG (SyncWorker_5) [homeassistant.components.sensor.miflora] Set initial state 2019-03-19 18:54:10 DEBUG (SyncWorker_2) [homeassistant.components.sensor.miflora] Data collected: [43] 2019-03-19 18:54:10 DEBUG (SyncWorker_8) [homeassistant.components.sensor.miflora] Suculenta Moisture = 18 2019-03-19 18:54:10 DEBUG (SyncWorker_10) [miflora.miflora_poller] Using cache (0:00:00.015931 < 0:20:00) 2019-03-19 18:54:11 DEBUG (SyncWorker_2) [homeassistant.components.sensor.miflora] Set initial state 2019-03-19 18:54:11 INFO (MainThread) [homeassistant.components.device_tracker.asuswrt] Checking Devices 2019-03-19 18:54:11 DEBUG (SyncWorker_8) [homeassistant.components.sensor.miflora] Data collected: [18] 2019-03-19 18:54:11 DEBUG (SyncWorker_10) [homeassistant.components.sensor.miflora] Suculenta Light intensity = 0 2019-03-19 18:54:11 INFO (MainThread) [asyncssh] [conn=0, chan=8] Requesting new SSH session 2019-03-19 18:54:11 DEBUG (SyncWorker_8) [homeassistant.components.sensor.miflora] Set initial state 2019-03-19 18:54:11 DEBUG (SyncWorker_10) [homeassistant.components.sensor.miflora] Data collected: [0] 2019-03-19 18:54:11 DEBUG (SyncWorker_10) [homeassistant.components.sensor.miflora] Set initial state

Description of problem: See errors above.

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