Core: RFLink, UnicodeDecodeError: 'utf-8'

Created on 17 May 2019  Â·  50Comments  Â·  Source: home-assistant/core

Home Assistant release with the issue:
0.93.1

Last working Home Assistant release (if known):

Operating environment (Hass.io/Docker/Windows/etc.):
Hass.io

Component/platform:
https://www.home-assistant.io/components/rflink/

Description of problem:
RFLink does not work

Problem-relevant configuration.yaml entries and (fill out even if it seems unimportant):

rflink:
  port: /dev/ttyACM0

Traceback (if applicable):

Error doing job: Exception in callback <bound method SerialTransport._read_ready of SerialTransport(<uvloop.Loop running=True closed=False debug=False>, <rflink.protocol.RflinkProtocol object at 0x7f4f014a6b00>, Serial<id=0x7f4f01227390, open=True>(port='/dev/ttyACM0', baudrate=57600, bytesize=8, parity='N', stopbits=1, timeout=0, xonxoff=False, rtscts=False, dsrdtr=False))>
Traceback (most recent call last):
  File "uvloop/cbhandles.pyx", line 66, in uvloop.loop.Handle._run
  File "/usr/local/lib/python3.7/site-packages/serial_asyncio/__init__.py", line 106, in _read_ready
    self._protocol.data_received(data)
  File "/usr/local/lib/python3.7/site-packages/rflink/protocol.py", line 46, in data_received
    data = data.decode()
UnicodeDecodeError: 'utf-8' codec can't decode byte 0x98 in position 2: invalid start byte

Additional information:

rflink
Source
picture olskar

Most helpful comment

Could anyone having this problem with Ubuntu try the following and see if it helps?

Without the NetworkManager, you will be not able to control your host network setup over the UI. The modemmanager package will interfere with any Z-Wave or Zigbee stick and should be removed or disabled. Failure to do so will result in random failures of those integrations. For example you can disable with sudo systemctl disable ModemManager and remove with sudo apt-get purge modemmanager

picture olskar on 12 Sep 2019
👍2

All 50 comments

Another update on this bug, I can "fix" it by running a rflink-python command in the underlying os.

For exmaple sudo rflink on newkaku_00220600_1
When I run that, and hass is running as well, hass seem to work fine with rflink afterwards?

Do you have any idea @aequitas?

olskar picture olskar on 19 May 2019

I have the same issue. Problem started in 0.93.1

Connecting to rflink over ip (socat with rflink on anthother linux machine) works fine

degraafm picture degraafm on 25 May 2019

@degraafm does the problem manifest consistently or sporadically? Could you maybe try with a previous version to see when it was still working?

This change happened recently [0] but I don't know if its related?

[0] https://github.com/home-assistant/home-assistant/commit/2c07bfb9e0ac03fdf74b83152f4511ed7f7104b0#diff-d9ab991eeffd384c9d9f8528a2c546bf

aequitas picture aequitas on 25 May 2019

The problem manifests consistently. After rebooting/restarting rflink works for about 30 seconds. Then the unicode decode error is apppearing in the logs.

Rflink in Version 0.92.2 is working fine

degraafm picture degraafm on 25 May 2019

Hm i downgraded to 0.92.2 and have the same error

olskar picture olskar on 26 May 2019

I am having same problem. Home Assistant 0.93.2.

2019-05-30 12:12:55 INFO (MainThread) [rflink.protocol] disconnected because of close/abort.
tail: /usr/share/hassio/homeassistant/home-assistant.log: file truncated
2019-05-30 12:13:06 INFO (MainThread) [homeassistant.components.rflink] Initiating Rflink connection
2019-05-30 12:13:06 INFO (MainThread) [homeassistant.components.rflink] Connected to Rflink
2019-05-30 12:13:06 DEBUG (MainThread) [rflink.protocol] connected

2019-05-30 12:13:28 ERROR (MainThread) [homeassistant.core] Error doing job: Exception in callback <bound method SerialTransport._read_ready of SerialTransport(<uvloop.Loop running=True closed=False debug=False>, <rflink.protocol.RflinkProtocol object at 0x7feb3474cdd8>, Serial<id=0x7feb3474c438, open=True>(port='/dev/ttyACM0', baudrate=57600, bytesize=8, parity='N', stopbits=1, timeout=0, xonxoff=False, rtscts=False, dsrdtr=False))>
Traceback (most recent call last):
  File "uvloop/cbhandles.pyx", line 66, in uvloop.loop.Handle._run
  File "/usr/local/lib/python3.7/site-packages/serial_asyncio/__init__.py", line 106, in _read_ready
    self._protocol.data_received(data)
  File "/usr/local/lib/python3.7/site-packages/rflink/protocol.py", line 46, in data_received
    data = data.decode()
UnicodeDecodeError: 'utf-8' codec can't decode byte 0x98 in position 2: invalid start byte

2019-05-30 12:13:28 ERROR (MainThread) [homeassistant.core] Error doing job: Exception in callback <bound method SerialTransport._read_ready of SerialTransport(<uvloop.Loop running=True closed=False debug=False>, <rflink.protocol.RflinkProtocol object at 0x7feb3474cdd8>, Serial<id=0x7feb3474c438, open=True>(port='/dev/ttyACM0', baudrate=57600, bytesize=8, parity='N', stopbits=1, timeout=0, xonxoff=False, rtscts=False, dsrdtr=False))>
Traceback (most recent call last):
  File "uvloop/cbhandles.pyx", line 66, in uvloop.loop.Handle._run
  File "/usr/local/lib/python3.7/site-packages/serial_asyncio/__init__.py", line 106, in _read_ready
    self._protocol.data_received(data)
  File "/usr/local/lib/python3.7/site-packages/rflink/protocol.py", line 46, in data_received
    data = data.decode()
UnicodeDecodeError: 'utf-8' codec can't decode byte 0x98 in position 1: invalid start byte

2019-05-30 12:13:28 ERROR (MainThread) [homeassistant.core] Error doing job: Exception in callback <bound method SerialTransport._read_ready of SerialTransport(<uvloop.Loop running=True closed=False debug=False>, <rflink.protocol.RflinkProtocol object at 0x7feb3474cdd8>, Serial<id=0x7feb3474c438, open=True>(port='/dev/ttyACM0', baudrate=57600, bytesize=8, parity='N', stopbits=1, timeout=0, xonxoff=False, rtscts=False, dsrdtr=False))>
Traceback (most recent call last):
  File "uvloop/cbhandles.pyx", line 66, in uvloop.loop.Handle._run
  File "/usr/local/lib/python3.7/site-packages/serial_asyncio/__init__.py", line 106, in _read_ready
    self._protocol.data_received(data)
  File "/usr/local/lib/python3.7/site-packages/rflink/protocol.py", line 46, in data_received
    data = data.decode()
UnicodeDecodeError: 'utf-8' codec can't decode byte 0x86 in position 0: invalid start byte

2019-05-30 12:13:28 ERROR (MainThread) [homeassistant.core] Error doing job: Exception in callback <bound method SerialTransport._read_ready of SerialTransport(<uvloop.Loop running=True closed=False debug=False>, <rflink.protocol.RflinkProtocol object at 0x7feb3474cdd8>, Serial<id=0x7feb3474c438, open=True>(port='/dev/ttyACM0', baudrate=57600, bytesize=8, parity='N', stopbits=1, timeout=0, xonxoff=False, rtscts=False, dsrdtr=False))>
Traceback (most recent call last):
  File "uvloop/cbhandles.pyx", line 66, in uvloop.loop.Handle._run
  File "/usr/local/lib/python3.7/site-packages/serial_asyncio/__init__.py", line 106, in _read_ready
    self._protocol.data_received(data)
  File "/usr/local/lib/python3.7/site-packages/rflink/protocol.py", line 46, in data_received
    data = data.decode()
UnicodeDecodeError: 'utf-8' codec can't decode byte 0x98 in position 0: invalid start byte
IscreaMan picture IscreaMan on 30 May 2019

Could you please run HA with the debug on for rflink[0] and post relevant output here? I might get a better picture of what is going wrong when I know which data is coming in.

[0] https://www.home-assistant.io/components/rflink/#debug-logging

aequitas picture aequitas on 30 May 2019

My output is already posted with debug on.

2019-05-30 12:13:06 DEBUG (MainThread) [rflink.protocol] connected

IscreaMan picture IscreaMan on 30 May 2019

Between 02:03:08 and 02:03:25, I run sudo rflink on newkaku_00220600_1 on my ubuntu server (that has hass installed in docker) to turn a light on . After that, as you can see in the logs, rflink starts working.

Not sure if that can help in understanding whats going on @aequitas ?

2019-05-31 02:02:46 ERROR (MainThread) [homeassistant.core] Error doing job: Exception in callback <bound method SerialTransport._read_ready of SerialTransport(<uvloop.Loop running=True closed=False debug=False>, <rflink.protocol.RflinkProtocol object at 0x7fb5067b4128>, Serial<id=0x7fb50697bfd0, open=True>(port='/dev/ttyACM0', baudrate=57600, bytesize=8, parity='N', stopbits=1, timeout=0, xonxoff=False, rtscts=False, dsrdtr=False))>
Traceback (most recent call last):
  File "uvloop/cbhandles.pyx", line 66, in uvloop.loop.Handle._run
  File "/usr/local/lib/python3.7/site-packages/serial_asyncio/__init__.py", line 106, in _read_ready
    self._protocol.data_received(data)
  File "/usr/local/lib/python3.7/site-packages/rflink/protocol.py", line 46, in data_received
    data = data.decode()
UnicodeDecodeError: 'utf-8' codec can't decode byte 0x9e in position 0: invalid start byte
2019-05-31 02:02:46 ERROR (MainThread) [homeassistant.core] Error doing job: Exception in callback <bound method SerialTransport._read_ready of SerialTransport(<uvloop.Loop running=True closed=False debug=False>, <rflink.protocol.RflinkProtocol object at 0x7fb5067b4128>, Serial<id=0x7fb50697bfd0, open=True>(port='/dev/ttyACM0', baudrate=57600, bytesize=8, parity='N', stopbits=1, timeout=0, xonxoff=False, rtscts=False, dsrdtr=False))>
Traceback (most recent call last):
  File "uvloop/cbhandles.pyx", line 66, in uvloop.loop.Handle._run
  File "/usr/local/lib/python3.7/site-packages/serial_asyncio/__init__.py", line 106, in _read_ready
    self._protocol.data_received(data)
  File "/usr/local/lib/python3.7/site-packages/rflink/protocol.py", line 46, in data_received
    data = data.decode()
UnicodeDecodeError: 'utf-8' codec can't decode byte 0x9e in position 2: invalid start byte
2019-05-31 02:03:08 ERROR (MainThread) [homeassistant.core] Error doing job: Exception in callback <bound method SerialTransport._read_ready of SerialTransport(<uvloop.Loop running=True closed=False debug=False>, <rflink.protocol.RflinkProtocol object at 0x7fb5067b4128>, Serial<id=0x7fb50697bfd0, open=True>(port='/dev/ttyACM0', baudrate=57600, bytesize=8, parity='N', stopbits=1, timeout=0, xonxoff=False, rtscts=False, dsrdtr=False))>
Traceback (most recent call last):
  File "uvloop/cbhandles.pyx", line 66, in uvloop.loop.Handle._run
  File "/usr/local/lib/python3.7/site-packages/serial_asyncio/__init__.py", line 106, in _read_ready
    self._protocol.data_received(data)
  File "/usr/local/lib/python3.7/site-packages/rflink/protocol.py", line 46, in data_received
    data = data.decode()
UnicodeDecodeError: 'utf-8' codec can't decode byte 0x98 in position 2: invalid start byte
2019-05-31 02:03:08 ERROR (MainThread) [homeassistant.core] Error doing job: Exception in callback <bound method SerialTransport._read_ready of SerialTransport(<uvloop.Loop running=True closed=False debug=False>, <rflink.protocol.RflinkProtocol object at 0x7fb5067b4128>, Serial<id=0x7fb50697bfd0, open=True>(port='/dev/ttyACM0', baudrate=57600, bytesize=8, parity='N', stopbits=1, timeout=0, xonxoff=False, rtscts=False, dsrdtr=False))>
Traceback (most recent call last):
  File "uvloop/cbhandles.pyx", line 66, in uvloop.loop.Handle._run
  File "/usr/local/lib/python3.7/site-packages/serial_asyncio/__init__.py", line 106, in _read_ready
    self._protocol.data_received(data)
  File "/usr/local/lib/python3.7/site-packages/rflink/protocol.py", line 46, in data_received
    data = data.decode()
UnicodeDecodeError: 'utf-8' codec can't decode byte 0xe0 in position 0: invalid continuation byte
2019-05-31 02:03:08 ERROR (MainThread) [homeassistant.core] Error doing job: Exception in callback <bound method SerialTransport._read_ready of SerialTransport(<uvloop.Loop running=True closed=False debug=False>, <rflink.protocol.RflinkProtocol object at 0x7fb5067b4128>, Serial<id=0x7fb50697bfd0, open=True>(port='/dev/ttyACM0', baudrate=57600, bytesize=8, parity='N', stopbits=1, timeout=0, xonxoff=False, rtscts=False, dsrdtr=False))>
Traceback (most recent call last):
  File "uvloop/cbhandles.pyx", line 66, in uvloop.loop.Handle._run
  File "/usr/local/lib/python3.7/site-packages/serial_asyncio/__init__.py", line 106, in _read_ready
    self._protocol.data_received(data)
  File "/usr/local/lib/python3.7/site-packages/rflink/protocol.py", line 46, in data_received
    data = data.decode()
UnicodeDecodeError: 'utf-8' codec can't decode byte 0x98 in position 5: invalid start byte
2019-05-31 02:03:25 DEBUG (MainThread) [rflink.protocol] received data: 20;07;OK;
2019-05-31 02:03:25 DEBUG (MainThread) [rflink.protocol] got packet: 20;07;OK;
2019-05-31 02:03:25 DEBUG (MainThread) [rflink.protocol] decoded packet: {'node': 'gateway', 'protocol': 'unknown', 'ok': True}
2019-05-31 02:03:25 DEBUG (MainThread) [rflink.protocol] command response: {'node': 'gateway', 'protocol': 'unknown', 'ok': True}
2019-05-31 02:03:25 DEBUG (MainThread) [rflink.protocol] received data: 20
2019-05-31 02:03:25 DEBUG (MainThread) [rflink.protocol] received data: ;08;Oregon TempHygro;ID=
2019-05-31 02:03:25 DEBUG (MainThread) [rflink.protocol] received data: 2DED;TEMP=005d;HUM=86;HS
2019-05-31 02:03:25 DEBUG (MainThread) [rflink.protocol] received data: TATUS=3;BAT=OK;
2019-05-31 02:03:25 DEBUG (MainThread) [rflink.protocol] got packet: 20;08;Oregon TempHygro;ID=2DED;TEMP=005d;HUM=86;HSTATUS=3;BAT=OK;
2019-05-31 02:03:25 DEBUG (MainThread) [rflink.protocol] decoded packet: {'node': 'gateway', 'protocol': 'oregon temphygro', 'id': '2ded', 'temperature': 9.3, 'temperature_unit': '°C', 'humidity': 86, 'humidity_unit': '%', 'humidity_status': 'wet', 'battery': 'ok'}
2019-05-31 02:03:25 DEBUG (MainThread) [rflink.protocol] got event: {'id': 'oregontemphygro_2ded_temp', 'sensor': 'temperature', 'value': 9.3, 'unit': '°C'}
2019-05-31 02:03:25 DEBUG (MainThread) [homeassistant.components.rflink] event of type sensor: {'id': 'oregontemphygro_2ded_temp', 'sensor': 'temperature', 'value': 9.3, 'unit': '°C'}
2019-05-31 02:03:25 DEBUG (MainThread) [homeassistant.components.rflink] entity_ids: []
2019-05-31 02:03:25 DEBUG (MainThread) [homeassistant.components.rflink] device_id not known and automatic add disabled
2019-05-31 02:03:25 DEBUG (MainThread) [rflink.protocol] got event: {'id': 'oregontemphygro_2ded_hum', 'sensor': 'humidity', 'value': 86, 'unit': '%'}
2019-05-31 02:03:25 DEBUG (MainThread) [homeassistant.components.rflink] event of type sensor: {'id': 'oregontemphygro_2ded_hum', 'sensor': 'humidity', 'value': 86, 'unit': '%'}
2019-05-31 02:03:25 DEBUG (MainThread) [homeassistant.components.rflink] entity_ids: []
2019-05-31 02:03:25 DEBUG (MainThread) [homeassistant.components.rflink] device_id not known and automatic add disabled
2019-05-31 02:03:25 DEBUG (MainThread) [rflink.protocol] got event: {'id': 'oregontemphygro_2ded_hstatus', 'sensor': 'humidity_status', 'value': 'wet', 'unit': None}
2019-05-31 02:03:25 DEBUG (MainThread) [homeassistant.components.rflink] event of type sensor: {'id': 'oregontemphygro_2ded_hstatus', 'sensor': 'humidity_status', 'value': 'wet', 'unit': None}
2019-05-31 02:03:25 DEBUG (MainThread) [homeassistant.components.rflink] entity_ids: []
2019-05-31 02:03:25 DEBUG (MainThread) [homeassistant.components.rflink] device_id not known and automatic add disabled
2019-05-31 02:03:25 DEBUG (MainThread) [rflink.protocol] got event: {'id': 'oregontemphygro_2ded_bat', 'sensor': 'battery', 'value': 'ok', 'unit': None}
2019-05-31 02:03:25 DEBUG (MainThread) [homeassistant.components.rflink] event of type sensor: {'id': 'oregontemphygro_2ded_bat', 'sensor': 'battery', 'value': 'ok', 'unit': None}
2019-05-31 02:03:25 DEBUG (MainThread) [homeassistant.components.rflink] entity_ids: []
2019-05-31 02:03:25 DEBUG (MainThread) [homeassistant.components.rflink] device_id not known and automatic add disabled
2019-05-31 02:03:26 DEBUG (MainThread) [rflink.protocol] received data: 20;09;FineOffs
2019-05-31 02:03:26 DEBUG (MainThread) [rflink.protocol] received data: et;ID=00ff;TEMP=0066;
2019-05-31 02:03:26 DEBUG (MainThread) [rflink.protocol] got packet: 20;09;FineOffset;ID=00ff;TEMP=0066;
2019-05-31 02:03:26 DEBUG (MainThread) [rflink.protocol] decoded packet: {'node': 'gateway', 'protocol': 'fineoffset', 'id': '00ff', 'temperature': 10.2, 'temperature_unit': '°C'}
2019-05-31 02:03:26 DEBUG (MainThread) [rflink.protocol] got event: {'id': 'fineoffset_00ff_temp', 'sensor': 'temperature', 'value': 10.2, 'unit': '°C'}
2019-05-31 02:03:26 DEBUG (MainThread) [homeassistant.components.rflink] event of type sensor: {'id': 'fineoffset_00ff_temp', 'sensor': 'temperature', 'value': 10.2, 'unit': '°C'}
2019-05-31 02:03:26 DEBUG (MainThread) [homeassistant.components.rflink] entity_ids: []
2019-05-31 02:03:26 DEBUG (MainThread) [homeassistant.components.rflink] device_id not known and automatic add disabled
2019-05-31 02:03:28 DEBUG (MainThread) [rflink.protocol] received data: 20;0A;WH3;ID=43d0;TE
2019-05-31 02:03:28 DEBUG (MainThread) [rflink.protocol] received data: MP=0073;HUM=75;
2019-05-31 02:03:28 DEBUG (MainThread) [rflink.protocol] got packet: 20;0A;WH3;ID=43d0;TEMP=0073;HUM=75;
2019-05-31 02:03:28 DEBUG (MainThread) [rflink.protocol] decoded packet: {'node': 'gateway', 'protocol': 'wh3', 'id': '43d0', 'temperature': 11.5, 'temperature_unit': '°C', 'humidity': 75, 'humidity_unit': '%'}
2019-05-31 02:03:28 DEBUG (MainThread) [rflink.protocol] got event: {'id': 'wh3_43d0_temp', 'sensor': 'temperature', 'value': 11.5, 'unit': '°C'}
2019-05-31 02:03:28 DEBUG (MainThread) [homeassistant.components.rflink] event of type sensor: {'id': 'wh3_43d0_temp', 'sensor': 'temperature', 'value': 11.5, 'unit': '°C'}
olskar picture olskar on 31 May 2019

Between 02:03:08 and 02:03:25, I run sudo rflink on newkaku_00220600_1 on my ubuntu server (that has hass installed in docker) to turn a light on . After that, as you can see in the logs, rflink starts working.

I can confirm that this also works on in my setup (homeassistant running inside docker here as well).

A little snippet of my debug output as well (before running the above mentioned command);

2019-05-31 02:20:31 ERROR (MainThread) [homeassistant.core] Error doing job: Exception in callback <bound method SerialTransport._read_ready of SerialTransport(<uvloop.Loop running=True closed=False debug=False>, <rflink.protocol.RflinkProtocol object at 0x7fc1de9944e0>, Serial<id=0x7fc1df59e4a8, open=True>(port='/dev/serial/by-id/usb-Arduino__www.arduino.cc__0042_55739323138351D06162-if00', baudrate=57600, bytesize=8, parity='N', stopbits=1, timeout=0, xonxoff=False, rtscts=False, dsrdtr=False))>
Traceback (most recent call last):
  File "uvloop/cbhandles.pyx", line 66, in uvloop.loop.Handle._run
  File "/usr/local/lib/python3.7/site-packages/serial_asyncio/__init__.py", line 106, in _read_ready
    self._protocol.data_received(data)
  File "/usr/local/lib/python3.7/site-packages/rflink/protocol.py", line 46, in data_received
    data = data.decode()
UnicodeDecodeError: 'utf-8' codec can't decode byte 0xe6 in position 0: invalid continuation byte
2019-05-31 02:20:31 ERROR (MainThread) [homeassistant.core] Error doing job: Exception in callback <bound method SerialTransport._read_ready of SerialTransport(<uvloop.Loop running=True closed=False debug=False>, <rflink.protocol.RflinkProtocol object at 0x7fc1de9944e0>, Serial<id=0x7fc1df59e4a8, open=True>(port='/dev/serial/by-id/usb-Arduino__www.arduino.cc__0042_55739323138351D06162-if00', baudrate=57600, bytesize=8, parity='N', stopbits=1, timeout=0, xonxoff=False, rtscts=False, dsrdtr=False))>
Traceback (most recent call last):
  File "uvloop/cbhandles.pyx", line 66, in uvloop.loop.Handle._run
  File "/usr/local/lib/python3.7/site-packages/serial_asyncio/__init__.py", line 106, in _read_ready
    self._protocol.data_received(data)
  File "/usr/local/lib/python3.7/site-packages/rflink/protocol.py", line 46, in data_received
    data = data.decode()
UnicodeDecodeError: 'utf-8' codec can't decode byte 0x9e in position 0: invalid start byte
2019-05-31 02:22:29 ERROR (MainThread) [homeassistant.core] Error doing job: Exception in callback <bound method SerialTransport._read_ready of SerialTransport(<uvloop.Loop running=True closed=False debug=False>, <rflink.protocol.RflinkProtocol object at 0x7fc1de9944e0>, Serial<id=0x7fc1df59e4a8, open=True>(port='/dev/serial/by-id/usb-Arduino__www.arduino.cc__0042_55739323138351D06162-if00', baudrate=57600, bytesize=8, parity='N', stopbits=1, timeout=0, xonxoff=False, rtscts=False, dsrdtr=False))>
Traceback (most recent call last):
  File "uvloop/cbhandles.pyx", line 66, in uvloop.loop.Handle._run
  File "/usr/local/lib/python3.7/site-packages/serial_asyncio/__init__.py", line 106, in _read_ready
    self._protocol.data_received(data)
  File "/usr/local/lib/python3.7/site-packages/rflink/protocol.py", line 46, in data_received
    data = data.decode()
UnicodeDecodeError: 'utf-8' codec can't decode byte 0x98 in position 2: invalid start byte
2019-05-31 02:22:29 ERROR (MainThread) [homeassistant.core] Error doing job: Exception in callback <bound method SerialTransport._read_ready of SerialTransport(<uvloop.Loop running=True closed=False debug=False>, <rflink.protocol.RflinkProtocol object at 0x7fc1de9944e0>, Serial<id=0x7fc1df59e4a8, open=True>(port='/dev/serial/by-id/usb-Arduino__www.arduino.cc__0042_55739323138351D06162-if00', baudrate=57600, bytesize=8, parity='N', stopbits=1, timeout=0, xonxoff=False, rtscts=False, dsrdtr=False))>
Traceback (most recent call last):
  File "uvloop/cbhandles.pyx", line 66, in uvloop.loop.Handle._run
  File "/usr/local/lib/python3.7/site-packages/serial_asyncio/__init__.py", line 106, in _read_ready
    self._protocol.data_received(data)
  File "/usr/local/lib/python3.7/site-packages/rflink/protocol.py", line 46, in data_received
    data = data.decode()
UnicodeDecodeError: 'utf-8' codec can't decode byte 0x98 in position 0: invalid start byte
2019-05-31 02:23:28 ERROR (MainThread) [homeassistant.core] Error doing job: Exception in callback <bound method SerialTransport._read_ready of SerialTransport(<uvloop.Loop running=True closed=False debug=False>, <rflink.protocol.RflinkProtocol object at 0x7fc1de9944e0>, Serial<id=0x7fc1df59e4a8, open=True>(port='/dev/serial/by-id/usb-Arduino__www.arduino.cc__0042_55739323138351D06162-if00', baudrate=57600, bytesize=8, parity='N', stopbits=1, timeout=0, xonxoff=False, rtscts=False, dsrdtr=False))>
Traceback (most recent call last):
  File "uvloop/cbhandles.pyx", line 66, in uvloop.loop.Handle._run
  File "/usr/local/lib/python3.7/site-packages/serial_asyncio/__init__.py", line 106, in _read_ready
    self._protocol.data_received(data)
  File "/usr/local/lib/python3.7/site-packages/rflink/protocol.py", line 46, in data_received
    data = data.decode()
UnicodeDecodeError: 'utf-8' codec can't decode byte 0x98 in position 2: invalid start byte
2019-05-31 02:23:28 ERROR (MainThread) [homeassistant.core] Error doing job: Exception in callback <bound method SerialTransport._read_ready of SerialTransport(<uvloop.Loop running=True closed=False debug=False>, <rflink.protocol.RflinkProtocol object at 0x7fc1de9944e0>, Serial<id=0x7fc1df59e4a8, open=True>(port='/dev/serial/by-id/usb-Arduino__www.arduino.cc__0042_55739323138351D06162-if00', baudrate=57600, bytesize=8, parity='N', stopbits=1, timeout=0, xonxoff=False, rtscts=False, dsrdtr=False))>
Traceback (most recent call last):
  File "uvloop/cbhandles.pyx", line 66, in uvloop.loop.Handle._run
  File "/usr/local/lib/python3.7/site-packages/serial_asyncio/__init__.py", line 106, in _read_ready
    self._protocol.data_received(data)
  File "/usr/local/lib/python3.7/site-packages/rflink/protocol.py", line 46, in data_received
    data = data.decode()
UnicodeDecodeError: 'utf-8' codec can't decode byte 0x9e in position 0: invalid start byte
bendikrb picture bendikrb on 31 May 2019

It seems the problem occurs only on docker/hass.io. I can reproduce the problem om ubuntu with hass.io. On a raspberry pi with hass installed in a python virtual env. Rflink is working fine.

degraafm picture degraafm on 31 May 2019

Yesterday i noticed the same error.

2019-06-02 09:24:35 ERROR (MainThread) [homeassistant.core] Error doing job: Exception in callback <bound method SerialTransport._read_ready of SerialTransport(<uvloop.Loop running=True closed=False debug=False>, <rflink.protocol.RflinkProtocol object at 0x7f980d11e160>, Serial<id=0x7f9809df2b38, open=True>(port='/dev/ttyACM0', baudrate=57600, bytesize=8, parity='N', stopbits=1, timeout=0, xonxoff=False, rtscts=False, dsrdtr=False))>
Traceback (most recent call last):
  File "uvloop/cbhandles.pyx", line 66, in uvloop.loop.Handle._run
  File "/usr/local/lib/python3.7/site-packages/serial_asyncio/__init__.py", line 106, in _read_ready
    self._protocol.data_received(data)
  File "/usr/local/lib/python3.7/site-packages/rflink/protocol.py", line 46, in data_received
    data = data.decode()
UnicodeDecodeError: 'utf-8' codec can't decode byte 0x9e in position 0: invalid start byte

Hassio 0.93.2
Intel NUC with Ubuntu and docker.

Sjeff picture Sjeff on 2 Jun 2019

So I've finally come around to update my hassio installation to HA 0.93.2 but the problem doesn't manifest itself here. So I'm not able to debug this locally.

Which version of hassio are you running. Mine is at 165, I have a pretty old installation so maybe it's lagging behind and the problem only exists on newer hassio versions?

aequitas picture aequitas on 2 Jun 2019

I updated python-rflink and created a pr to include the latest version in HA. This should result in a more robust handling of invalid incoming data.

Beware this will not fix the issue per se. It will just ignore the data, log a warning and continue. But previously the invalid data would not be logged at all, not even in debug. So this might give a better insight in why things go wrong.

aequitas picture aequitas on 2 Jun 2019

Mine is at 165 as well. How are you running hassio @aequitas ? Is it on pi or something else?

olskar picture olskar on 3 Jun 2019

@olskar yes, on an RPi 3b+. Rflink plugged into the USB.

One thing might be worth mentioning is that I don't use the /dev/ttyACM0 device path but a unique per-device path: /dev/serial/by-id/usb-Arduino__www.arduino.cc__0042_XXXXXXXXXXXXXXXXXXX-if00

This path will be different for your configuration. You can view them like so:
root@hassio:~# ls -l /dev/serial/by-id/ total 0 lrwxrwxrwx 1 root root 13 Jun 2 18:16 usb-Arduino__www.arduino.cc__0042_55632313338351B08080-if00 -> ../../ttyACM0 lrwxrwxrwx 1 root root 13 Jun 2 18:16 usb-Prolific_Technology_Inc._USB-Serial_Controller-if00-port0 -> ../../ttyUSB0

It is because I have multiple serial devices and they sometime get assigned different numbers when booting the raspberry pi. Using the unique paths ensures I get the correct device for Rflink.

I don't think this is problem that is playing on your end. As you told it works when you invoke Rflink directly (which defaults to /dev/ttyACM0). But for others this might be the issue. As input from any other serial device than a Rflink could generate invalid data for the rflink module.

aequitas picture aequitas on 3 Jun 2019

I am starting to think it might be related to docker+hassio+rflink and exposing device to the docker container

I have installed hassio manually on my Intel nuc, @sjeff also running on nuc

olskar picture olskar on 3 Jun 2019

@aequitas Here is log with 0.46. (At 16:00:46 I send a command with rflink on host os).

019-06-03 15:59:11 WARNING (MainThread) [homeassistant.loader] You are using a custom integration for rflink which has not been tested by Home Assistant. This component might cause stability problems, be sure to disable it if you do experience issues with Home Assistant. 2019-06-03 15:59:11 WARNING (MainThread) [homeassistant.loader] You are using a custom integration for custom_updater which has not been tested by Home Assistant. This component might cause stability problems, be sure to disable it if you do experience issues with Home Assistant. 2019-06-03 15:59:11 DEBUG (MainThread) [rflink.protocol] connected 2019-06-03 15:59:35 WARNING (MainThread) [rflink.protocol] Error during decode of data, invalid data: �~�~�����
2019-06-03 15:59:35 WARNING (MainThread) [rflink.protocol] Error during decode of data, invalid data: �fx����x��fxf~f��f�������������� 2019-06-03 15:59:35 WARNING (MainThread) [rflink.protocol] Error during decode of data, invalid data: ������~f�~ff������~怘
2019-06-03 15:59:35 WARNING (MainThread) [rflink.protocol] Error during decode of data, invalid data: �
2019-06-03 15:59:38 DEBUG (MainThread) [rflink.protocol] sending command: {'protocol': 'newkaku', 'id': '01351e16', 'switch': 'b', 'command': '0'}
2019-06-03 15:59:38 DEBUG (MainThread) [rflink.protocol] writing data: '10;newkaku;01351e16;b;0;\r\n'
2019-06-03 15:59:38 DEBUG (MainThread) [rflink.protocol] waiting for acknowledgement
2019-06-03 15:59:39 WARNING (MainThread) [rflink.protocol] Error during decode of data, invalid data: �~���~f憞f�~��f
2019-06-03 15:59:39 WARNING (MainThread) [rflink.protocol] Error during decode of data, invalid data: �����~ff�f�xf�~��fff���f 2019-06-03 15:59:39 WARNING (MainThread) [rflink.protocol] Error during decode of data, invalid data: f昘������ 2019-06-03 15:59:43 WARNING (MainThread) [rflink.protocol] acknowledge timeout 2019-06-03 15:59:53 WARNING (MainThread) [rflink.protocol] Error during decode of data, invalid data: �~x���f��~�� 2019-06-03 15:59:53 WARNING (MainThread) [rflink.protocol] Error during decode of data, invalid data: ff�~��f����fff�f���f�~ff���
2019-06-03 15:59:53 WARNING (MainThread) [rflink.protocol] Error during decode of data, invalid data: �xx��ff昘������
2019-06-03 16:00:12 WARNING (MainThread) [rflink.protocol] Error during decode of data, invalid data: �~�x�ff�~��f�� 2019-06-03 16:00:12 WARNING (MainThread) [rflink.protocol] Error during decode of data, invalid data: ��~ff�f����x��ff昘������
2019-06-03 16:00:20 WARNING (MainThread) [rflink.protocol] Error during decode of data, invalid data: �~�����f�x�xfx�~��f 2019-06-03 16:00:20 WARNING (MainThread) [rflink.protocol] Error during decode of data, invalid data: xx�~ff�f���x����
2019-06-03 16:00:27 WARNING (MainThread) [rflink.protocol] Error during decode of data, invalid data: �~�
2019-06-03 16:00:27 WARNING (MainThread) [rflink.protocol] Error during decode of data, invalid data: �~~f��~��fx�~ff�f�xx�~�
2019-06-03 16:00:27 WARNING (MainThread) [rflink.protocol] Error during decode of data, invalid data: �fff���~怘�
2019-06-03 16:00:36 WARNING (MainThread) [rflink.protocol] Error during decode of data, invalid data: �~�ff��f
2019-06-03 16:00:36 WARNING (MainThread) [rflink.protocol] Error during decode of data, invalid data: �~��f�����~ff�f�xf�~��fff
2019-06-03 16:00:36 WARNING (MainThread) [rflink.protocol] Error during decode of data, invalid data: ���ff昘������
2019-06-03 16:00:39 WARNING (MainThread) [rflink.protocol] Error during decode of data, invalid data: �~�
2019-06-03 16:00:39 WARNING (MainThread) [rflink.protocol] Error during decode of data, invalid data: ��f��x�������������~��fx���~ff�f�����fff��~怘� 2019-06-03 16:00:39 WARNING (MainThread) [rflink.protocol] Error during decode of data, invalid data: �~�~����x�������������~
2019-06-03 16:00:39 WARNING (MainThread) [rflink.protocol] Error during decode of data, invalid data: ��fx���~ff�f�����fff��
2019-06-03 16:00:39 WARNING (MainThread) [rflink.protocol] Error during decode of data, invalid data: ~怘�
2019-06-03 16:00:41 WARNING (MainThread) [rflink.protocol] Error during decode of data, invalid data: �~���~�f�
2019-06-03 16:00:41 WARNING (MainThread) [rflink.protocol] Error during decode of data, invalid data: �~��ff�~��f����fff�f���f�~ 2019-06-03 16:00:41 WARNING (MainThread) [rflink.protocol] Error during decode of data, invalid data: ff����xx��ff昘������ 2019-06-03 16:00:42 WARNING (MainThread) [rflink.protocol] Error during decode of data, invalid data: �~��~xf���f�x�x 2019-06-03 16:00:42 WARNING (MainThread) [rflink.protocol] Error during decode of data, invalid data: fx�~��f��fff�f���fx��ff 2019-06-03 16:00:42 WARNING (MainThread) [rflink.protocol] Error during decode of data, invalid data: f������ 2019-06-03 16:00:46 DEBUG (MainThread) [rflink.protocol] received data: 20;0B;OK; 2019-06-03 16:00:46 DEBUG (MainThread) [rflink.protocol] got packet: 20;0B;OK; 2019-06-03 16:00:46 DEBUG (MainThread) [rflink.protocol] decoded packet: {'node': 'gateway', 'protocol': 'unknown', 'ok': True} 2019-06-03 16:00:46 DEBUG (MainThread) [rflink.protocol] command response: {'node': 'gateway', 'protocol': 'unknown', 'ok': True} 2019-06-03 16:01:09 DEBUG (MainThread) [rflink.protocol] received data: 20;0C;XT200 2019-06-03 16:01:09 DEBUG (MainThread) [rflink.protocol] received data: ;ID=1068;TEMP=00f0;BAT=L 2019-06-03 16:01:09 DEBUG (MainThread) [rflink.protocol] received data: OW; 2019-06-03 16:01:09 DEBUG (MainThread) [rflink.protocol] got packet: 20;0C;XT200;ID=1068;TEMP=00f0;BAT=LOW; 2019-06-03 16:01:09 DEBUG (MainThread) [rflink.protocol] decoded packet: {'node': 'gateway', 'protocol': 'xt200', 'id': '1068', 'temperature': 24.0, 'temperature_unit': '°C', 'battery': 'low'}

olskar picture olskar on 3 Jun 2019

Debug log HA 0.93.2
Also changed the port from /dev/ttyACM0 to /dev/serial/by-id/usb-Arduino__www.arduino.cc__0042_5573632383935140B2C1-if00 to see if its related but the error's and warnings remain the same.

I only have 1 device connected to rflink to switch on and off a light.
This device is not responding to any on/off commands.

2019-06-04 10:57:33 INFO (SyncWorker_10) [homeassistant.loader] Loaded rflink from homeassistant.components.rflink
2019-06-04 10:57:33 INFO (MainThread) [homeassistant.components.rflink] Initiating Rflink connection
2019-06-04 10:57:33 INFO (MainThread) [homeassistant.components.rflink] Connected to Rflink
2019-06-04 10:57:33 DEBUG (MainThread) [rflink.protocol] connected
2019-06-04 10:57:34 INFO (MainThread) [homeassistant.components.light] Setting up light.rflink
2019-06-04 10:58:07 ERROR (MainThread) [homeassistant.core] Error doing job: Exception in callback <bound method SerialTransport._read_ready of SerialTransport(<uvloop.Loop running=True closed=False debug=False>, <rflink.protocol.RflinkProtocol object at 0x7f68943dc550>, Serial<id=0x7f6894b7f860, open=True>(port='/dev/serial/by-id/usb-Arduino__www.arduino.cc__0042_5573632383935140B2C1-if00', baudrate=57600, bytesize=8, parity='N', stopbits=1, timeout=0, xonxoff=False, rtscts=False, dsrdtr=False))>
  File "/usr/local/lib/python3.7/site-packages/rflink/protocol.py", line 46, in data_received
2019-06-04 10:58:07 ERROR (MainThread) [homeassistant.core] Error doing job: Exception in callback <bound method SerialTransport._read_ready of SerialTransport(<uvloop.Loop running=True closed=False debug=False>, <rflink.protocol.RflinkProtocol object at 0x7f68943dc550>, Serial<id=0x7f6894b7f860, open=True>(port='/dev/serial/by-id/usb-Arduino__www.arduino.cc__0042_5573632383935140B2C1-if00', baudrate=57600, bytesize=8, parity='N', stopbits=1, timeout=0, xonxoff=False, rtscts=False, dsrdtr=False))>
  File "/usr/local/lib/python3.7/site-packages/rflink/protocol.py", line 46, in data_received
2019-06-04 10:58:07 ERROR (MainThread) [homeassistant.core] Error doing job: Exception in callback <bound method SerialTransport._read_ready of SerialTransport(<uvloop.Loop running=True closed=False debug=False>, <rflink.protocol.RflinkProtocol object at 0x7f68943dc550>, Serial<id=0x7f6894b7f860, open=True>(port='/dev/serial/by-id/usb-Arduino__www.arduino.cc__0042_5573632383935140B2C1-if00', baudrate=57600, bytesize=8, parity='N', stopbits=1, timeout=0, xonxoff=False, rtscts=False, dsrdtr=False))>
  File "/usr/local/lib/python3.7/site-packages/rflink/protocol.py", line 46, in data_received
2019-06-04 10:59:04 ERROR (MainThread) [homeassistant.core] Error doing job: Exception in callback <bound method SerialTransport._read_ready of SerialTransport(<uvloop.Loop running=True closed=False debug=False>, <rflink.protocol.RflinkProtocol object at 0x7f68943dc550>, Serial<id=0x7f6894b7f860, open=True>(port='/dev/serial/by-id/usb-Arduino__www.arduino.cc__0042_5573632383935140B2C1-if00', baudrate=57600, bytesize=8, parity='N', stopbits=1, timeout=0, xonxoff=False, rtscts=False, dsrdtr=False))>
  File "/usr/local/lib/python3.7/site-packages/rflink/protocol.py", line 46, in data_received
2019-06-04 10:59:04 ERROR (MainThread) [homeassistant.core] Error doing job: Exception in callback <bound method SerialTransport._read_ready of SerialTransport(<uvloop.Loop running=True closed=False debug=False>, <rflink.protocol.RflinkProtocol object at 0x7f68943dc550>, Serial<id=0x7f6894b7f860, open=True>(port='/dev/serial/by-id/usb-Arduino__www.arduino.cc__0042_5573632383935140B2C1-if00', baudrate=57600, bytesize=8, parity='N', stopbits=1, timeout=0, xonxoff=False, rtscts=False, dsrdtr=False))>
  File "/usr/local/lib/python3.7/site-packages/rflink/protocol.py", line 46, in data_received
2019-06-04 10:59:04 ERROR (MainThread) [homeassistant.core] Error doing job: Exception in callback <bound method SerialTransport._read_ready of SerialTransport(<uvloop.Loop running=True closed=False debug=False>, <rflink.protocol.RflinkProtocol object at 0x7f68943dc550>, Serial<id=0x7f6894b7f860, open=True>(port='/dev/serial/by-id/usb-Arduino__www.arduino.cc__0042_5573632383935140B2C1-if00', baudrate=57600, bytesize=8, parity='N', stopbits=1, timeout=0, xonxoff=False, rtscts=False, dsrdtr=False))>
  File "/usr/local/lib/python3.7/site-packages/rflink/protocol.py", line 46, in data_received
2019-06-04 11:00:01 ERROR (MainThread) [homeassistant.core] Error doing job: Exception in callback <bound method SerialTransport._read_ready of SerialTransport(<uvloop.Loop running=True closed=False debug=False>, <rflink.protocol.RflinkProtocol object at 0x7f68943dc550>, Serial<id=0x7f6894b7f860, open=True>(port='/dev/serial/by-id/usb-Arduino__www.arduino.cc__0042_5573632383935140B2C1-if00', baudrate=57600, bytesize=8, parity='N', stopbits=1, timeout=0, xonxoff=False, rtscts=False, dsrdtr=False))>
  File "/usr/local/lib/python3.7/site-packages/rflink/protocol.py", line 46, in data_received
2019-06-04 11:00:01 ERROR (MainThread) [homeassistant.core] Error doing job: Exception in callback <bound method SerialTransport._read_ready of SerialTransport(<uvloop.Loop running=True closed=False debug=False>, <rflink.protocol.RflinkProtocol object at 0x7f68943dc550>, Serial<id=0x7f6894b7f860, open=True>(port='/dev/serial/by-id/usb-Arduino__www.arduino.cc__0042_5573632383935140B2C1-if00', baudrate=57600, bytesize=8, parity='N', stopbits=1, timeout=0, xonxoff=False, rtscts=False, dsrdtr=False))>
  File "/usr/local/lib/python3.7/site-packages/rflink/protocol.py", line 46, in data_received
2019-06-04 11:00:01 ERROR (MainThread) [homeassistant.core] Error doing job: Exception in callback <bound method SerialTransport._read_ready of SerialTransport(<uvloop.Loop running=True closed=False debug=False>, <rflink.protocol.RflinkProtocol object at 0x7f68943dc550>, Serial<id=0x7f6894b7f860, open=True>(port='/dev/serial/by-id/usb-Arduino__www.arduino.cc__0042_5573632383935140B2C1-if00', baudrate=57600, bytesize=8, parity='N', stopbits=1, timeout=0, xonxoff=False, rtscts=False, dsrdtr=False))>
  File "/usr/local/lib/python3.7/site-packages/rflink/protocol.py", line 46, in data_received
2019-06-04 11:00:58 ERROR (MainThread) [homeassistant.core] Error doing job: Exception in callback <bound method SerialTransport._read_ready of SerialTransport(<uvloop.Loop running=True closed=False debug=False>, <rflink.protocol.RflinkProtocol object at 0x7f68943dc550>, Serial<id=0x7f6894b7f860, open=True>(port='/dev/serial/by-id/usb-Arduino__www.arduino.cc__0042_5573632383935140B2C1-if00', baudrate=57600, bytesize=8, parity='N', stopbits=1, timeout=0, xonxoff=False, rtscts=False, dsrdtr=False))>
  File "/usr/local/lib/python3.7/site-packages/rflink/protocol.py", line 46, in data_received
2019-06-04 11:00:58 ERROR (MainThread) [homeassistant.core] Error doing job: Exception in callback <bound method SerialTransport._read_ready of SerialTransport(<uvloop.Loop running=True closed=False debug=False>, <rflink.protocol.RflinkProtocol object at 0x7f68943dc550>, Serial<id=0x7f6894b7f860, open=True>(port='/dev/serial/by-id/usb-Arduino__www.arduino.cc__0042_5573632383935140B2C1-if00', baudrate=57600, bytesize=8, parity='N', stopbits=1, timeout=0, xonxoff=False, rtscts=False, dsrdtr=False))>
  File "/usr/local/lib/python3.7/site-packages/rflink/protocol.py", line 46, in data_received
2019-06-04 11:00:58 ERROR (MainThread) [homeassistant.core] Error doing job: Exception in callback <bound method SerialTransport._read_ready of SerialTransport(<uvloop.Loop running=True closed=False debug=False>, <rflink.protocol.RflinkProtocol object at 0x7f68943dc550>, Serial<id=0x7f6894b7f860, open=True>(port='/dev/serial/by-id/usb-Arduino__www.arduino.cc__0042_5573632383935140B2C1-if00', baudrate=57600, bytesize=8, parity='N', stopbits=1, timeout=0, xonxoff=False, rtscts=False, dsrdtr=False))>
  File "/usr/local/lib/python3.7/site-packages/rflink/protocol.py", line 46, in data_received
2019-06-04 11:01:55 ERROR (MainThread) [homeassistant.core] Error doing job: Exception in callback <bound method SerialTransport._read_ready of SerialTransport(<uvloop.Loop running=True closed=False debug=False>, <rflink.protocol.RflinkProtocol object at 0x7f68943dc550>, Serial<id=0x7f6894b7f860, open=True>(port='/dev/serial/by-id/usb-Arduino__www.arduino.cc__0042_5573632383935140B2C1-if00', baudrate=57600, bytesize=8, parity='N', stopbits=1, timeout=0, xonxoff=False, rtscts=False, dsrdtr=False))>
  File "/usr/local/lib/python3.7/site-packages/rflink/protocol.py", line 46, in data_received
2019-06-04 11:01:55 ERROR (MainThread) [homeassistant.core] Error doing job: Exception in callback <bound method SerialTransport._read_ready of SerialTransport(<uvloop.Loop running=True closed=False debug=False>, <rflink.protocol.RflinkProtocol object at 0x7f68943dc550>, Serial<id=0x7f6894b7f860, open=True>(port='/dev/serial/by-id/usb-Arduino__www.arduino.cc__0042_5573632383935140B2C1-if00', baudrate=57600, bytesize=8, parity='N', stopbits=1, timeout=0, xonxoff=False, rtscts=False, dsrdtr=False))>
  File "/usr/local/lib/python3.7/site-packages/rflink/protocol.py", line 46, in data_received
2019-06-04 11:01:55 ERROR (MainThread) [homeassistant.core] Error doing job: Exception in callback <bound method SerialTransport._read_ready of SerialTransport(<uvloop.Loop running=True closed=False debug=False>, <rflink.protocol.RflinkProtocol object at 0x7f68943dc550>, Serial<id=0x7f6894b7f860, open=True>(port='/dev/serial/by-id/usb-Arduino__www.arduino.cc__0042_5573632383935140B2C1-if00', baudrate=57600, bytesize=8, parity='N', stopbits=1, timeout=0, xonxoff=False, rtscts=False, dsrdtr=False))>
  File "/usr/local/lib/python3.7/site-packages/rflink/protocol.py", line 46, in data_received
2019-06-04 11:02:52 ERROR (MainThread) [homeassistant.core] Error doing job: Exception in callback <bound method SerialTransport._read_ready of SerialTransport(<uvloop.Loop running=True closed=False debug=False>, <rflink.protocol.RflinkProtocol object at 0x7f68943dc550>, Serial<id=0x7f6894b7f860, open=True>(port='/dev/serial/by-id/usb-Arduino__www.arduino.cc__0042_5573632383935140B2C1-if00', baudrate=57600, bytesize=8, parity='N', stopbits=1, timeout=0, xonxoff=False, rtscts=False, dsrdtr=False))>
  File "/usr/local/lib/python3.7/site-packages/rflink/protocol.py", line 46, in data_received
2019-06-04 11:02:52 ERROR (MainThread) [homeassistant.core] Error doing job: Exception in callback <bound method SerialTransport._read_ready of SerialTransport(<uvloop.Loop running=True closed=False debug=False>, <rflink.protocol.RflinkProtocol object at 0x7f68943dc550>, Serial<id=0x7f6894b7f860, open=True>(port='/dev/serial/by-id/usb-Arduino__www.arduino.cc__0042_5573632383935140B2C1-if00', baudrate=57600, bytesize=8, parity='N', stopbits=1, timeout=0, xonxoff=False, rtscts=False, dsrdtr=False))>
  File "/usr/local/lib/python3.7/site-packages/rflink/protocol.py", line 46, in data_received
2019-06-04 11:04:46 ERROR (MainThread) [homeassistant.core] Error doing job: Exception in callback <bound method SerialTransport._read_ready of SerialTransport(<uvloop.Loop running=True closed=False debug=False>, <rflink.protocol.RflinkProtocol object at 0x7f68943dc550>, Serial<id=0x7f6894b7f860, open=True>(port='/dev/serial/by-id/usb-Arduino__www.arduino.cc__0042_5573632383935140B2C1-if00', baudrate=57600, bytesize=8, parity='N', stopbits=1, timeout=0, xonxoff=False, rtscts=False, dsrdtr=False))>
  File "/usr/local/lib/python3.7/site-packages/rflink/protocol.py", line 46, in data_received
2019-06-04 11:04:46 ERROR (MainThread) [homeassistant.core] Error doing job: Exception in callback <bound method SerialTransport._read_ready of SerialTransport(<uvloop.Loop running=True closed=False debug=False>, <rflink.protocol.RflinkProtocol object at 0x7f68943dc550>, Serial<id=0x7f6894b7f860, open=True>(port='/dev/serial/by-id/usb-Arduino__www.arduino.cc__0042_5573632383935140B2C1-if00', baudrate=57600, bytesize=8, parity='N', stopbits=1, timeout=0, xonxoff=False, rtscts=False, dsrdtr=False))>
  File "/usr/local/lib/python3.7/site-packages/rflink/protocol.py", line 46, in data_received
2019-06-04 11:05:43 DEBUG (MainThread) [rflink.protocol] received data: 
2019-06-04 11:05:43 ERROR (MainThread) [homeassistant.core] Error doing job: Exception in callback <bound method SerialTransport._read_ready of SerialTransport(<uvloop.Loop running=True closed=False debug=False>, <rflink.protocol.RflinkProtocol object at 0x7f68943dc550>, Serial<id=0x7f6894b7f860, open=True>(port='/dev/serial/by-id/usb-Arduino__www.arduino.cc__0042_5573632383935140B2C1-if00', baudrate=57600, bytesize=8, parity='N', stopbits=1, timeout=0, xonxoff=False, rtscts=False, dsrdtr=False))>
  File "/usr/local/lib/python3.7/site-packages/rflink/protocol.py", line 46, in data_received
2019-06-04 11:05:43 ERROR (MainThread) [homeassistant.core] Error doing job: Exception in callback <bound method SerialTransport._read_ready of SerialTransport(<uvloop.Loop running=True closed=False debug=False>, <rflink.protocol.RflinkProtocol object at 0x7f68943dc550>, Serial<id=0x7f6894b7f860, open=True>(port='/dev/serial/by-id/usb-Arduino__www.arduino.cc__0042_5573632383935140B2C1-if00', baudrate=57600, bytesize=8, parity='N', stopbits=1, timeout=0, xonxoff=False, rtscts=False, dsrdtr=False))>
  File "/usr/local/lib/python3.7/site-packages/rflink/protocol.py", line 46, in data_received
2019-06-04 11:06:40 ERROR (MainThread) [homeassistant.core] Error doing job: Exception in callback <bound method SerialTransport._read_ready of SerialTransport(<uvloop.Loop running=True closed=False debug=False>, <rflink.protocol.RflinkProtocol object at 0x7f68943dc550>, Serial<id=0x7f6894b7f860, open=True>(port='/dev/serial/by-id/usb-Arduino__www.arduino.cc__0042_5573632383935140B2C1-if00', baudrate=57600, bytesize=8, parity='N', stopbits=1, timeout=0, xonxoff=False, rtscts=False, dsrdtr=False))>
  File "/usr/local/lib/python3.7/site-packages/rflink/protocol.py", line 46, in data_received
2019-06-04 11:06:40 ERROR (MainThread) [homeassistant.core] Error doing job: Exception in callback <bound method SerialTransport._read_ready of SerialTransport(<uvloop.Loop running=True closed=False debug=False>, <rflink.protocol.RflinkProtocol object at 0x7f68943dc550>, Serial<id=0x7f6894b7f860, open=True>(port='/dev/serial/by-id/usb-Arduino__www.arduino.cc__0042_5573632383935140B2C1-if00', baudrate=57600, bytesize=8, parity='N', stopbits=1, timeout=0, xonxoff=False, rtscts=False, dsrdtr=False))>
  File "/usr/local/lib/python3.7/site-packages/rflink/protocol.py", line 46, in data_received
2019-06-04 11:06:40 ERROR (MainThread) [homeassistant.core] Error doing job: Exception in callback <bound method SerialTransport._read_ready of SerialTransport(<uvloop.Loop running=True closed=False debug=False>, <rflink.protocol.RflinkProtocol object at 0x7f68943dc550>, Serial<id=0x7f6894b7f860, open=True>(port='/dev/serial/by-id/usb-Arduino__www.arduino.cc__0042_5573632383935140B2C1-if00', baudrate=57600, bytesize=8, parity='N', stopbits=1, timeout=0, xonxoff=False, rtscts=False, dsrdtr=False))>
  File "/usr/local/lib/python3.7/site-packages/rflink/protocol.py", line 46, in data_received
2019-06-04 11:07:37 ERROR (MainThread) [homeassistant.core] Error doing job: Exception in callback <bound method SerialTransport._read_ready of SerialTransport(<uvloop.Loop running=True closed=False debug=False>, <rflink.protocol.RflinkProtocol object at 0x7f68943dc550>, Serial<id=0x7f6894b7f860, open=True>(port='/dev/serial/by-id/usb-Arduino__www.arduino.cc__0042_5573632383935140B2C1-if00', baudrate=57600, bytesize=8, parity='N', stopbits=1, timeout=0, xonxoff=False, rtscts=False, dsrdtr=False))>
  File "/usr/local/lib/python3.7/site-packages/rflink/protocol.py", line 46, in data_received
2019-06-04 11:07:37 ERROR (MainThread) [homeassistant.core] Error doing job: Exception in callback <bound method SerialTransport._read_ready of SerialTransport(<uvloop.Loop running=True closed=False debug=False>, <rflink.protocol.RflinkProtocol object at 0x7f68943dc550>, Serial<id=0x7f6894b7f860, open=True>(port='/dev/serial/by-id/usb-Arduino__www.arduino.cc__0042_5573632383935140B2C1-if00', baudrate=57600, bytesize=8, parity='N', stopbits=1, timeout=0, xonxoff=False, rtscts=False, dsrdtr=False))>
  File "/usr/local/lib/python3.7/site-packages/rflink/protocol.py", line 46, in data_received
2019-06-04 11:08:34 ERROR (MainThread) [homeassistant.core] Error doing job: Exception in callback <bound method SerialTransport._read_ready of SerialTransport(<uvloop.Loop running=True closed=False debug=False>, <rflink.protocol.RflinkProtocol object at 0x7f68943dc550>, Serial<id=0x7f6894b7f860, open=True>(port='/dev/serial/by-id/usb-Arduino__www.arduino.cc__0042_5573632383935140B2C1-if00', baudrate=57600, bytesize=8, parity='N', stopbits=1, timeout=0, xonxoff=False, rtscts=False, dsrdtr=False))>
  File "/usr/local/lib/python3.7/site-packages/rflink/protocol.py", line 46, in data_received
2019-06-04 11:08:34 ERROR (MainThread) [homeassistant.core] Error doing job: Exception in callback <bound method SerialTransport._read_ready of SerialTransport(<uvloop.Loop running=True closed=False debug=False>, <rflink.protocol.RflinkProtocol object at 0x7f68943dc550>, Serial<id=0x7f6894b7f860, open=True>(port='/dev/serial/by-id/usb-Arduino__www.arduino.cc__0042_5573632383935140B2C1-if00', baudrate=57600, bytesize=8, parity='N', stopbits=1, timeout=0, xonxoff=False, rtscts=False, dsrdtr=False))>
 File "/usr/local/lib/python3.7/site-packages/rflink/protocol.py", line 46, in data_received
2019-06-04 11:08:34 ERROR (MainThread) [homeassistant.core] Error doing job: Exception in callback <bound method SerialTransport._read_ready of SerialTransport(<uvloop.Loop running=True closed=False debug=False>, <rflink.protocol.RflinkProtocol object at 0x7f68943dc550>, Serial<id=0x7f6894b7f860, open=True>(port='/dev/serial/by-id/usb-Arduino__www.arduino.cc__0042_5573632383935140B2C1-if00', baudrate=57600, bytesize=8, parity='N', stopbits=1, timeout=0, xonxoff=False, rtscts=False, dsrdtr=False))>
  File "/usr/local/lib/python3.7/site-packages/rflink/protocol.py", line 46, in data_received
2019-06-04 11:09:31 ERROR (MainThread) [homeassistant.core] Error doing job: Exception in callback <bound method SerialTransport._read_ready of SerialTransport(<uvloop.Loop running=True closed=False debug=False>, <rflink.protocol.RflinkProtocol object at 0x7f68943dc550>, Serial<id=0x7f6894b7f860, open=True>(port='/dev/serial/by-id/usb-Arduino__www.arduino.cc__0042_5573632383935140B2C1-if00', baudrate=57600, bytesize=8, parity='N', stopbits=1, timeout=0, xonxoff=False, rtscts=False, dsrdtr=False))>
  File "/usr/local/lib/python3.7/site-packages/rflink/protocol.py", line 46, in data_received
2019-06-04 11:09:31 ERROR (MainThread) [homeassistant.core] Error doing job: Exception in callback <bound method SerialTransport._read_ready of SerialTransport(<uvloop.Loop running=True closed=False debug=False>, <rflink.protocol.RflinkProtocol object at 0x7f68943dc550>, Serial<id=0x7f6894b7f860, open=True>(port='/dev/serial/by-id/usb-Arduino__www.arduino.cc__0042_5573632383935140B2C1-if00', baudrate=57600, bytesize=8, parity='N', stopbits=1, timeout=0, xonxoff=False, rtscts=False, dsrdtr=False))>
  File "/usr/local/lib/python3.7/site-packages/rflink/protocol.py", line 46, in data_received
2019-06-04 11:09:31 ERROR (MainThread) [homeassistant.core] Error doing job: Exception in callback <bound method SerialTransport._read_ready of SerialTransport(<uvloop.Loop running=True closed=False debug=False>, <rflink.protocol.RflinkProtocol object at 0x7f68943dc550>, Serial<id=0x7f6894b7f860, open=True>(port='/dev/serial/by-id/usb-Arduino__www.arduino.cc__0042_5573632383935140B2C1-if00', baudrate=57600, bytesize=8, parity='N', stopbits=1, timeout=0, xonxoff=False, rtscts=False, dsrdtr=False))>
  File "/usr/local/lib/python3.7/site-packages/rflink/protocol.py", line 46, in data_received
2019-06-04 11:09:45 DEBUG (MainThread) [homeassistant.components.rflink] Sending command: 3 to Rflink device: newkaku_00fd0f56_10
2019-06-04 11:09:45 DEBUG (MainThread) [rflink.protocol] sending command: {'protocol': 'newkaku', 'id': '00fd0f56', 'switch': '10', 'command': '3'}
2019-06-04 11:09:45 DEBUG (MainThread) [rflink.protocol] writing data: '10;newkaku;00fd0f56;10;3;\r\n'
2019-06-04 11:09:45 DEBUG (MainThread) [rflink.protocol] waiting for acknowledgement
2019-06-04 11:09:50 WARNING (MainThread) [rflink.protocol] acknowledge timeout
2019-06-04 11:09:50 DEBUG (MainThread) [homeassistant.components.rflink] Sending command: 3 to Rflink device: newkaku_00fd0f56_10
2019-06-04 11:09:50 DEBUG (MainThread) [rflink.protocol] sending command: {'protocol': 'newkaku', 'id': '00fd0f56', 'switch': '10', 'command': '3'}
2019-06-04 11:09:50 DEBUG (MainThread) [rflink.protocol] writing data: '10;newkaku;00fd0f56;10;3;\r\n'
2019-06-04 11:09:50 DEBUG (MainThread) [rflink.protocol] waiting for acknowledgement
2019-06-04 11:09:55 WARNING (MainThread) [rflink.protocol] acknowledge timeout
2019-06-04 11:10:02 DEBUG (MainThread) [homeassistant.components.rflink] Sending command: off to Rflink device: newkaku_00fd0f56_10
2019-06-04 11:10:02 DEBUG (MainThread) [rflink.protocol] sending command: {'protocol': 'newkaku', 'id': '00fd0f56', 'switch': '10', 'command': 'off'}
2019-06-04 11:10:02 DEBUG (MainThread) [rflink.protocol] writing data: '10;newkaku;00fd0f56;10;off;\r\n'
2019-06-04 11:10:02 DEBUG (MainThread) [rflink.protocol] waiting for acknowledgement
2019-06-04 11:10:07 WARNING (MainThread) [rflink.protocol] acknowledge timeout
2019-06-04 11:10:28 ERROR (MainThread) [homeassistant.core] Error doing job: Exception in callback <bound method SerialTransport._read_ready of SerialTransport(<uvloop.Loop running=True closed=False debug=False>, <rflink.protocol.RflinkProtocol object at 0x7f68943dc550>, Serial<id=0x7f6894b7f860, open=True>(port='/dev/serial/by-id/usb-Arduino__www.arduino.cc__0042_5573632383935140B2C1-if00', baudrate=57600, bytesize=8, parity='N', stopbits=1, timeout=0, xonxoff=False, rtscts=False, dsrdtr=False))>
  File "/usr/local/lib/python3.7/site-packages/rflink/protocol.py", line 46, in data_received
2019-06-04 11:10:28 ERROR (MainThread) [homeassistant.core] Error doing job: Exception in callback <bound method SerialTransport._read_ready of SerialTransport(<uvloop.Loop running=True closed=False debug=False>, <rflink.protocol.RflinkProtocol object at 0x7f68943dc550>, Serial<id=0x7f6894b7f860, open=True>(port='/dev/serial/by-id/usb-Arduino__www.arduino.cc__0042_5573632383935140B2C1-if00', baudrate=57600, bytesize=8, parity='N', stopbits=1, timeout=0, xonxoff=False, rtscts=False, dsrdtr=False))>
  File "/usr/local/lib/python3.7/site-packages/rflink/protocol.py", line 46, in data_received
2019-06-04 11:10:28 ERROR (MainThread) [homeassistant.core] Error doing job: Exception in callback <bound method SerialTransport._read_ready of SerialTransport(<uvloop.Loop running=True closed=False debug=False>, <rflink.protocol.RflinkProtocol object at 0x7f68943dc550>, Serial<id=0x7f6894b7f860, open=True>(port='/dev/serial/by-id/usb-Arduino__www.arduino.cc__0042_5573632383935140B2C1-if00', baudrate=57600, bytesize=8, parity='N', stopbits=1, timeout=0, xonxoff=False, rtscts=False, dsrdtr=False))>
  File "/usr/local/lib/python3.7/site-packages/rflink/protocol.py", line 46, in data_received
Sjeff picture Sjeff on 4 Jun 2019

If I go to portainer and mark "home assistant" and "hassio_supervisor" and then press restart, I do not get any errors.

But if I restart home assistant from inside home assistant, I get the above errors.

So this seems to be a bug connected to hass.io somehow.

Don't know if we could tag someone that knows a lot about hass.io that perhaps can understand why this occurs?

olskar picture olskar on 4 Jun 2019

I tried the same as @olskar but that didn't work for me.
Receiving the same error and the light doesn't turn on or off.

My portainer install is a separate docker container not a hass.io addon.

Sjeff picture Sjeff on 4 Jun 2019
😕1

@olskar I haven't been around much in the HA community lately so I don't know who currently is most knowledgeable about the hassio system. Maybe @balloob or @pvizeli know?

Correct me if I'm wrong, but what we know at the moment is its:

  • not rflink (version) specific, since not everyone using rflink (eg me) has issues, version of rflink has not changed recently
  • not HA version specific as downgrading from 0.93.1 to previously working 0.92.2 does not work for everyone
  • might be hassio specific, but we have one hassio on 165 working and one not, so it's not hassio version specific. But maybe related to how/when your hassio was setup?
  • seems related to how the container interacts with the serial device (technically the container only binds the serial device inside the container, so not much that could go wrong there).
  • running rflink outside of the HA container works and in some cases restores functionality inside the container
aequitas picture aequitas on 5 Jun 2019

I give up. Tried everythinh including clean Home Assistant instalation on a different machine. Is there any progress on solution? :)

This looks like similar problem 2 years ago: https://community.home-assistant.io/t/solved-rflink-flooding-the-log-file/18762

IscreaMan picture IscreaMan on 12 Jun 2019

For some reason the method i used earlier (restart docker container) does not work any more, hopefully this bug can be fixed

olskar picture olskar on 19 Jun 2019

I have the same issue, using Ubuntu 18.04 and docker
I noticed that if you watch the serialinterface (for example with screen) you can see that from the moment the home assistant docker is starting the output is becoming garbage:

screen /dev/ttyACM0 57600
RadioFrequencyLink - RFLink Gateway V1.1 - R46;
20;01;XT200;ID=1019;TEMP=0028;BAT=LOW;
�~�~���`��fx����x��fxf~f��f��������`����`~f�~ff����x�~怘��~��~f~f�����`����fff�f�����ff昘�������~x�ff�~��`f�����~`ff�f�x�~ff����~f~怘��~�x��f~f�����`����fff�f�����ff昘���������~`ff�f�x�~ff����~f~怘��~��f~f�����`����fff�f�����ff昘������f�f�x�~ff����~f~怘�f�����~`ff�f�xf�~ff����~f~怘��~��~f~f�����`����fff�f�����ff昘���������~f~f�����`����fff�f�����ff昘������xx�~ff����~f~怘��~��~f~f�����`����fff�f�����f�~`��f~f�����`��~�~�ff�~��`f�����~`ff�f��x�~ff����~f~怘��~x��f~f�����`����fff�f�����ff昘�������~�x��f~f�����`����fff�f�����ff昘�������~�ff�~��`f�����~`ff�f��x�~ff����~f~怘��x`�~ff����~f~怘��~��ff�~��`f�����~`ff�f��x`�~ff����~f~怘��~�~f~f�����`����fff�f�����ff昘�������~�ff�~��`f�����~`ff�f��xx�~ff����~f~怘��~��~f~f�����`����fff�f���f��ff昘�������~��~f~f�����`����fff�f���f��ff昘�������~��`f�����~`ff�f��xx�~ff����~f~怘����fff�f���f��ff昘�������~��~f~f�����`����fff�f���f��ff昘�������~`��f~f�����`����fff�f���f��ff昘�������~�~��~���x��`��~ff��`f����~f�`f�����
j-insan3 picture j-insan3 on 19 Jun 2019

I can confirm same thing as @j-insan3 happens for me when watching the serial interface and starting home assistant docker container.

20;5D;FineOffset;ID=0087;TEMP=00e4;HUM=51; 20;5F;Oregon TempHygro;ID=2DED;TEMP=00a7;HUM=31;HSTATUS=2;BAT=OK; ▒~▒~▒▒▒▒▒fx▒▒▒▒x▒▒fxf~f▒▒f▒▒▒▒▒▒▒▒▒▒~f▒~ff▒▒▒▒▒▒~怘 `

So rflink does not seem to be the problem, ofcourse it can not make sense of that garbage.

I have no idea anymore but to me it indeed seems to be a problem related to how the container interacts with the serial device?

olskar picture olskar on 21 Jun 2019

Hi Guy's,

I have a workaround for you thats fixed the RFLink issues for me. This workaround has been rock solid for me. Maybe it can help someone else too.

1) install ser2net on ubuntu > apt-get install ser2net
2) add the line "1234:raw:300:/dev/ttyACM1:57600 8DATABITS NONE 1STOPBIT" to /etc/ser2net.conf" Where 1234 is the port number (if port 1234 is not free change the port number to a free one). Also make sure that you put in the right serial port. In my case it is /dev/ttyACM1
3) change the homeassistant rflink config to (see https://www.home-assistant.io/components/rflink/ for more information):

rflink:
host: [IP-Address of the host where ser2net is running]
port: 1234
wait_for_ack: false

4) restart ser2net > sudo systemctl restart ser2net.service
5) restart Home Assistant

degraafm76 picture degraafm76 on 24 Jun 2019
👍2

Hi Guy's,

I have a workaround for you thats fixed the RFLink issues for me. This workaround has been rock solid for me. Maybe it can help someone else too.

  1. install ser2net on ubuntu > apt-get install ser2net
  2. add the line "1234:raw:300:/dev/ttyACM1:57600 8DATABITS NONE 1STOPBIT" to /etc/ser2net.conf" Where 1234 is the port number (if port 1234 is not free change the port number to a free one). Also make sure that you put in the right serial port. In my case it is /dev/ttyACM1
  3. change the homeassistant rflink config to (see https://www.home-assistant.io/components/rflink/ for more information):

rflink:
host: [IP-Address of the host where ser2net is running]
port: 1234
wait_for_ack: false

  1. restart ser2net > sudo systemctl restart ser2net.service
  2. restart Home Assistant

Great solution, works like a charm bypassing buggy serial port interaction with docker/HA! Thank you.

IscreaMan picture IscreaMan on 26 Jun 2019

I'm getting this same issues and the workaround doesnt work for me. Running Hass.io latest on a Intel NUC. Is there any solution, my rflink devices no longer show and my log gets full of this kind of stuff:

2019-07-21 18:45:56 WARNING (MainThread) [rflink.protocol] Error during decode of data, invalid data: �~ �x�ff��f�~��`f�~���~`ff�
2019-07-21 18:45:56 WARNING (MainThread) [rflink.protocol] Error during decode of data, invalid data:  f��xx�~��fff�~ff昘������
2019-07-21 18:45:59 WARNING (MainThread) [rflink.protocol] acknowledge timeout
2019-07-21 18:46:04 WARNING (MainThread) [rflink.protocol] acknowledge timeout
2019-07-21 18:46:09 WARNING (MainThread) [rflink.protocol] acknowledge timeout
2019-07-21 18:46:13 WARNING (MainThread) [rflink.protocol] Error during decode of data, invalid data: �~ �f����
2019-07-21 18:46:13 WARNING (MainThread) [rflink.protocol] Error during decode of data, invalid data: ��~f~f�~��`f��~x�fff� f������
2019-07-21 18:46:13 WARNING (MainThread) [rflink.protocol] Error during decode of data, invalid data: fff`�~怘�
2019-07-21 18:46:14 WARNING (MainThread) [rflink.protocol] acknowledge timeout
2019-07-21 18:46:19 WARNING (MainThread) [rflink.protocol] acknowledge timeout
2019-07-21 18:46:23 WARNING (MainThread) [rflink.protocol] Error during decode of data, invalid data: �~ ��~f��f��~
2019-07-21 18:46:23 WARNING (MainThread) [rflink.protocol] Error during decode of data, invalid data:  f�f���~��`f����f`�~ff��`f������~f
2019-07-21 18:46:23 WARNING (MainThread) [rflink.protocol] Error during decode of data, invalid data: �`f�����������ff���~怘�
fleshinachair picture fleshinachair on 21 Jul 2019

Same issue here. Only started with upgrade to 0.96.4. Garbage serial data as per others here.

matthewharrington picture matthewharrington on 25 Jul 2019

I have also tried the workaround with ser2net and am still getting errors.

matthewharrington picture matthewharrington on 25 Jul 2019

it's only started happening in the last couple of weeks for me and I always update to latest release. Worked flawlessly before that.

fleshinachair picture fleshinachair on 25 Jul 2019

So, I have managed to get this going with a workaround.
I am running the Rflink using Ser2Net as per the instructions halfway through this thread. Any restart of Home Assistant causes corrupted data from the RFlink.
I can get the rflink running after a restart of Hassio by stopping the Ser2Net service and then starting it again after Home Assistant is running. Once the service is restarted it runs fine until the next HASSIO restart. In my case it ran for a week without issue until I had to restart Home Assistant. It seems to be something to do with the RFLink data coming into Hassio before it starts.

I hope this helps someone. Cheers, Matt

matthewharrington picture matthewharrington on 4 Aug 2019

Recently i got this only on restart, now it can occur suddenly, anyone able to confirm that? This on 0.97.2

olskar picture olskar on 23 Aug 2019

I tried today to install home assistant in docker using these instructions:
https://www.home-assistant.io/docs/installation/docker/

The bug is not present in that installation.

So i guess somehow it has to be related to hass.io?

olskar picture olskar on 27 Aug 2019

Same problems here with running Hassio in docker under Ubuntu server, rflink is unusable. I'm running Ubuntu on my esxi server, with passthrough to my usb card. Evereything else usb seem to work.

RoosterX picture RoosterX on 30 Aug 2019

No Python skills here at all but I think the ability to either delay the start of this component when HASSIO starts or restarts will work around the problem. Is it possible to add a start delay to a component? This issue only occurs on a HASSIO restart and stopping the serial flow and restarting once HASSIO results in normal behavior.

matthewharrington picture matthewharrington on 30 Aug 2019

I new to this docker way of doing things, so could it be a user error? In my installation I didn't setup a docker compose or similar to expose the different ports, since all ports seems to automatically show up under hardware in hass.io, could this be the problem I am seeing?

RoosterX picture RoosterX on 30 Aug 2019

No. It's an error that appeared reasonably recently with an upgrade of HASSIO. I am running SER2NET and when I restart my Home Assistant instance the corrupted data issue appears. Restarting the ser2net service resolves the issue.

matthewharrington picture matthewharrington on 30 Aug 2019

Yes I discovered the same thing(after reading here about ser2net).

RoosterX picture RoosterX on 30 Aug 2019

Same problems here with running Hassio in docker under Ubuntu server, rflink is unusable. I'm running Ubuntu on my esxi server, with passthrough to my usb card. Evereything else usb seem to work.

@RoosterX Is it possible for you to try the same thing as I did? E.g install home assistant in docker using these instructions and see if rflink works for you then?
https://www.home-assistant.io/docs/installation/docker/

No need to remove your original hass.io docker container, just stop it while testing out this one

olskar picture olskar on 1 Sep 2019

@matthewharrington What setup are you using? hass.io or hassos? RPI or other? Docker?

olskar picture olskar on 1 Sep 2019

Hassio for me here. Running on Ubuntu VM. I swapped to Ser2Net rather than the native rflink Home Assistant Component so I could restart Ser2Net as required to work around the corruption issue.

matthewharrington picture matthewharrington on 2 Sep 2019

Same problems here with running Hassio in docker under Ubuntu server, rflink is unusable. I'm running Ubuntu on my esxi server, with passthrough to my usb card. Evereything else usb seem to work.

@RoosterX Is it possible for you to try the same thing as I did? E.g install home assistant in docker using these instructions and see if rflink works for you then?
https://www.home-assistant.io/docs/installation/docker/

No need to remove your original hass.io docker container, just stop it while testing out this one

I'm using this https://www.home-assistant.io/hassio/installation/#alternative-install-on-a-generic-linux-host

I am not sure how to use the instruction in your link?

RoosterX picture RoosterX on 2 Sep 2019

Could anyone having this problem with Ubuntu try the following and see if it helps?

Without the NetworkManager, you will be not able to control your host network setup over the UI. The modemmanager package will interfere with any Z-Wave or Zigbee stick and should be removed or disabled. Failure to do so will result in random failures of those integrations. For example you can disable with sudo systemctl disable ModemManager and remove with sudo apt-get purge modemmanager

olskar picture olskar on 12 Sep 2019
👍2

I have just removed ModemManager and this seems to have sorted the issue for me. A single restart and rflink came up normally. Fingers crossed. Well done @olskar !

matthewharrington picture matthewharrington on 12 Sep 2019

Wow I will try this tonight. Would this solve issues I'm having with Bluetooth components which also stopped working from my NUC hassio recently? (e.g. miflora)

fleshinachair picture fleshinachair on 12 Sep 2019

Great!!!

"dpkg --purge modemmanager" and issue is gone :)

Thank you!

On Thu, 12 Sep 2019 at 10:16, Ã…skar Andersson notifications@github.com
wrote:

Could anyone having this problem with Ubuntu try the following and see if
it helps?

Without the NetworkManager, you will be not able to control your host
network setup over the UI. The modemmanager package will interfere with any
Z-Wave or Zigbee stick and should be removed or disabled. Failure to do so
will result in random failures of those integrations. For example you can
disable with sudo systemctl disable ModemManager and remove with sudo
apt-get purge modemmanager

—
You are receiving this because you commented.
Reply to this email directly, view it on GitHub
https://github.com/home-assistant/home-assistant/issues/23942?email_source=notifications&email_token=AE5JM77WLJDFIRVHIWDSZ63QJH3G3A5CNFSM4HNU6XG2YY3PNVWWK3TUL52HS4DFVREXG43VMVBW63LNMVXHJKTDN5WW2ZLOORPWSZGOD6RB3WQ#issuecomment-530718170,
or mute the thread
https://github.com/notifications/unsubscribe-auth/AE5JM75FXYHZ3WAKXYNY3T3QJH3G3ANCNFSM4HNU6XGQ
.

IscreaMan picture IscreaMan on 12 Sep 2019

I can confirm that this solves the issue here as well (gone through a couple of docker container restarts, and one cold boot of the host itself). Great! :+1:

bendikrb picture bendikrb on 12 Sep 2019

Great, closing this then!

olskar picture olskar on 12 Sep 2019
Was this page helpful?
0 / 5 - 0 ratings

Related issues

coolriku picture coolriku  Â·  3Comments
sogeniusio picture sogeniusio  Â·  3Comments
kirichkov picture kirichkov  Â·  3Comments
Konstigt picture Konstigt  Â·  3Comments
aweb-01 picture aweb-01  Â·  3Comments