Insteon_PLM on Hass.io, Anyone have it working?

Madelinot · March 17, 2018, 12:47pm

Part 2:

2018-03-17 08:35:00 DEBUG (MainThread) [homeassistant.components.binary_sensor.insteon_plm] Adding device 2d60ac entity leakSensor to Binary Sensor platform
2018-03-17 08:35:00 DEBUG (MainThread) [insteonplm.plm] Starting _get_from_send_queue
2018-03-17 08:35:00 DEBUG (MainThread) [insteonplm.plm] Ending _get_from_send_queue
2018-03-17 08:35:01 DEBUG (MainThread) [insteonplm.plm] Starting: data_received
2018-03-17 08:35:01 DEBUG (MainThread) [insteonplm.plm] Received 13 bytes from PLM: b'026a060257a2fb4038bb100844'
2018-03-17 08:35:01 DEBUG (MainThread) [insteonplm.plm] Total buffer: b'026a060257a2fb4038bb100844'
2018-03-17 08:35:01 DEBUG (MainThread) [insteonplm.plm] Starting: _peel_messages_from_buffer
2018-03-17 08:35:01 DEBUG (MainThread) [insteonplm.plm] Finishing: _peel_messages_from_buffer
2018-03-17 08:35:01 DEBUG (MainThread) [insteonplm.plm] Messages in queue: 2
2018-03-17 08:35:01 DEBUG (MainThread) [insteonplm.plm] Processing message {'code': 0x6a, 'acknak': 0x06}
2018-03-17 08:35:01 DEBUG (MainThread) [insteonplm.plm] No callback found for message {'code': 0x6a, 'acknak': 0x06}
2018-03-17 08:35:01 DEBUG (MainThread) [insteonplm.plm] Processing message {'code': 0x57, 'controlFlags': 0xa2, 'group': 0xfb, 'address': 40.38.BB, 'linkdata1': 0x10, 'linkdata2': 0x08, 'linkdata3': 0x44}
2018-03-17 08:35:01 DEBUG (MainThread) [insteonplm.plm] Calling method <function PLM._handle_all_link_record_response at 0x733506a8>
2018-03-17 08:35:01 DEBUG (MainThread) [insteonplm.plm] Last item in self._recv_queue reached.
2018-03-17 08:35:01 DEBUG (MainThread) [insteonplm.plm] Finishing: data_received
2018-03-17 08:35:01 DEBUG (MainThread) [insteonplm.states.stateBase] Registered callback for state: leakSensor
2018-03-17 08:35:01 DEBUG (MainThread) [insteonplm.plm] Starting _handle_all_link_record_response
2018-03-17 08:35:01 INFO (MainThread) [insteonplm.plm] Found all link record for device 4038bb
2018-03-17 08:35:01 DEBUG (MainThread) [insteonplm.plm] Starting: _get_next_all_link_recor
2018-03-17 08:35:01 INFO (MainThread) [insteonplm.plm] Requesting Next All-Link Record
2018-03-17 08:35:01 DEBUG (MainThread) [insteonplm.plm] Starting: send_msg
2018-03-17 08:35:01 DEBUG (MainThread) [insteonplm.plm] Ending: send_msg
2018-03-17 08:35:01 DEBUG (MainThread) [insteonplm.plm] Ending: _get_next_all_link_recor
2018-03-17 08:35:01 DEBUG (MainThread) [insteonplm.plm] Ending _handle_all_link_record_response
2018-03-17 08:35:01 DEBUG (MainThread) [insteonplm.plm] True
2018-03-17 08:35:01 DEBUG (MainThread) [insteonplm.plm] Writing message: {'code': 0x6a, 'acknak': None}
2018-03-17 08:35:01 DEBUG (MainThread) [insteonplm.states.stateBase] Registered callback for state: leakSensor
2018-03-17 08:35:01 DEBUG (MainThread) [insteonplm.plm] Starting _get_from_send_queue
2018-03-17 08:35:01 DEBUG (MainThread) [insteonplm.plm] Ending _get_from_send_queue
2018-03-17 08:35:02 DEBUG (MainThread) [insteonplm.plm] Starting: data_received
2018-03-17 08:35:02 DEBUG (MainThread) [insteonplm.plm] Received 13 bytes from PLM: b'026a060257e201284f3d100841'
2018-03-17 08:35:02 DEBUG (MainThread) [insteonplm.plm] Total buffer: b'026a060257e201284f3d100841'
2018-03-17 08:35:02 DEBUG (MainThread) [insteonplm.plm] Starting: _peel_messages_from_buffer
2018-03-17 08:35:02 DEBUG (MainThread) [insteonplm.plm] Finishing: _peel_messages_from_buffer
2018-03-17 08:35:02 DEBUG (MainThread) [insteonplm.plm] Messages in queue: 2
2018-03-17 08:35:02 DEBUG (MainThread) [insteonplm.plm] Processing message {'code': 0x6a, 'acknak': 0x06}
2018-03-17 08:35:02 DEBUG (MainThread) [insteonplm.plm] No callback found for message {'code': 0x6a, 'acknak': 0x06}
2018-03-17 08:35:02 DEBUG (MainThread) [insteonplm.plm] Processing message {'code': 0x57, 'controlFlags': 0xe2, 'group': 0x01, 'address': 28.4F.3D, 'linkdata1': 0x10, 'linkdata2': 0x08, 'linkdata3': 0x41}
2018-03-17 08:35:02 DEBUG (MainThread) [insteonplm.plm] Calling method <function PLM._handle_all_link_record_response at 0x733506a8>
2018-03-17 08:35:02 DEBUG (MainThread) [insteonplm.plm] Last item in self._recv_queue reached.
2018-03-17 08:35:02 DEBUG (MainThread) [insteonplm.plm] Finishing: data_received
2018-03-17 08:35:02 DEBUG (MainThread) [homeassistant.components.binary_sensor.insteon_plm] Adding device 4038bb entity leakSensor to Binary Sensor platform
2018-03-17 08:35:02 DEBUG (MainThread) [insteonplm.plm] Starting _handle_all_link_record_response
2018-03-17 08:35:02 INFO (MainThread) [insteonplm.plm] Found all link record for device 284f3d
2018-03-17 08:35:02 DEBUG (MainThread) [insteonplm.plm] Product data: address 284f3d cat: 10 subcat: 08 product_key: 41
2018-03-17 08:35:02 DEBUG (MainThread) [insteonplm.devices.devicebase] Registering DeviceBase._receive_message callbacks for device 28.4F.3D
2018-03-17 08:35:02 DEBUG (MainThread) [insteonplm.aldb] New INSTEON Device '284f3d': Water Leak Sensor (10:08)
2018-03-17 08:35:02 INFO (MainThread) [homeassistant.components.insteon_plm] New INSTEON PLM device: 284f3d (leakSensor) binary_sensor
2018-03-17 08:35:02 INFO (MainThread) [insteonplm.plm] Device with id 284f3d added to device list from ALDB data.
2018-03-17 08:35:02 DEBUG (MainThread) [insteonplm.plm] Starting: _get_next_all_link_recor
2018-03-17 08:35:02 INFO (MainThread) [insteonplm.plm] Requesting Next All-Link Record
2018-03-17 08:35:02 DEBUG (MainThread) [insteonplm.plm] Starting: send_msg
2018-03-17 08:35:02 DEBUG (MainThread) [insteonplm.plm] Ending: send_msg
2018-03-17 08:35:02 DEBUG (MainThread) [insteonplm.plm] Ending: _get_next_all_link_recor
2018-03-17 08:35:02 DEBUG (MainThread) [insteonplm.plm] Ending _handle_all_link_record_response
2018-03-17 08:35:02 DEBUG (MainThread) [insteonplm.plm] True
2018-03-17 08:35:02 DEBUG (MainThread) [insteonplm.plm] Writing message: {'code': 0x6a, 'acknak': None}
2018-03-17 08:35:02 DEBUG (MainThread) [insteonplm.plm] Starting _get_from_send_queue
2018-03-17 08:35:02 DEBUG (MainThread) [insteonplm.plm] Ending _get_from_send_queue
2018-03-17 08:35:02 DEBUG (MainThread) [insteonplm.states.stateBase] Registered callback for state: leakSensor
2018-03-17 08:35:03 DEBUG (MainThread) [insteonplm.plm] Starting: data_received
2018-03-17 08:35:03 DEBUG (MainThread) [insteonplm.plm] Received 13 bytes from PLM: b'026a060257a2fb284f3d100841'
2018-03-17 08:35:03 DEBUG (MainThread) [insteonplm.plm] Total buffer: b'026a060257a2fb284f3d100841'
2018-03-17 08:35:03 DEBUG (MainThread) [insteonplm.plm] Starting: _peel_messages_from_buffer
2018-03-17 08:35:03 DEBUG (MainThread) [insteonplm.plm] Finishing: _peel_messages_from_buffer
2018-03-17 08:35:03 DEBUG (MainThread) [insteonplm.plm] Messages in queue: 2
2018-03-17 08:35:03 DEBUG (MainThread) [insteonplm.plm] Processing message {'code': 0x6a, 'acknak': 0x06}
2018-03-17 08:35:03 DEBUG (MainThread) [insteonplm.plm] No callback found for message {'code': 0x6a, 'acknak': 0x06}
2018-03-17 08:35:03 DEBUG (MainThread) [insteonplm.plm] Processing message {'code': 0x57, 'controlFlags': 0xa2, 'group': 0xfb, 'address': 28.4F.3D, 'linkdata1': 0x10, 'linkdata2': 0x08, 'linkdata3': 0x41}
2018-03-17 08:35:03 DEBUG (MainThread) [insteonplm.plm] Calling method <function PLM._handle_all_link_record_response at 0x733506a8>
2018-03-17 08:35:04 DEBUG (MainThread) [insteonplm.plm] Last item in self._recv_queue reached.
2018-03-17 08:35:04 DEBUG (MainThread) [insteonplm.plm] Finishing: data_received
2018-03-17 08:35:04 DEBUG (MainThread) [insteonplm.plm] Starting _handle_all_link_record_response
2018-03-17 08:35:04 INFO (MainThread) [insteonplm.plm] Found all link record for device 284f3d
2018-03-17 08:35:04 DEBUG (MainThread) [insteonplm.plm] Starting: _get_next_all_link_recor
2018-03-17 08:35:04 INFO (MainThread) [insteonplm.plm] Requesting Next All-Link Record
2018-03-17 08:35:04 DEBUG (MainThread) [insteonplm.plm] Starting: send_msg
2018-03-17 08:35:04 DEBUG (MainThread) [insteonplm.plm] Ending: send_msg
2018-03-17 08:35:04 DEBUG (MainThread) [insteonplm.plm] Ending: _get_next_all_link_recor
2018-03-17 08:35:04 DEBUG (MainThread) [insteonplm.plm] Ending _handle_all_link_record_response
2018-03-17 08:35:04 DEBUG (MainThread) [insteonplm.plm] True
2018-03-17 08:35:04 DEBUG (MainThread) [insteonplm.plm] Writing message: {'code': 0x6a, 'acknak': None}
2018-03-17 08:35:04 DEBUG (MainThread) [homeassistant.components.binary_sensor.insteon_plm] Adding device 284f3d entity leakSensor to Binary Sensor platform
2018-03-17 08:35:04 DEBUG (MainThread) [insteonplm.plm] Starting _get_from_send_queue
2018-03-17 08:35:04 DEBUG (MainThread) [insteonplm.plm] Ending _get_from_send_queue
2018-03-17 08:35:05 DEBUG (MainThread) [insteonplm.plm] Starting: data_received
2018-03-17 08:35:05 DEBUG (MainThread) [insteonplm.plm] Received 13 bytes from PLM: b'026a060257e201237d6e090b3a'
2018-03-17 08:35:05 DEBUG (MainThread) [insteonplm.plm] Total buffer: b'026a060257e201237d6e090b3a'
2018-03-17 08:35:05 DEBUG (MainThread) [insteonplm.plm] Starting: _peel_messages_from_buffer
2018-03-17 08:35:05 DEBUG (MainThread) [insteonplm.plm] Finishing: _peel_messages_from_buffer
2018-03-17 08:35:05 DEBUG (MainThread) [insteonplm.plm] Messages in queue: 2
2018-03-17 08:35:05 DEBUG (MainThread) [insteonplm.plm] Processing message {'code': 0x6a, 'acknak': 0x06}
2018-03-17 08:35:05 DEBUG (MainThread) [insteonplm.plm] No callback found for message {'code': 0x6a, 'acknak': 0x06}
2018-03-17 08:35:05 DEBUG (MainThread) [insteonplm.plm] Processing message {'code': 0x57, 'controlFlags': 0xe2, 'group': 0x01, 'address': 23.7D.6E, 'linkdata1': 0x09, 'linkdata2': 0x0b, 'linkdata3': 0x3a}
2018-03-17 08:35:05 DEBUG (MainThread) [insteonplm.plm] Calling method <function PLM._handle_all_link_record_response at 0x733506a8>
2018-03-17 08:35:05 DEBUG (MainThread) [insteonplm.plm] Last item in self._recv_queue reached.
2018-03-17 08:35:05 DEBUG (MainThread) [insteonplm.plm] Finishing: data_received
2018-03-17 08:35:05 DEBUG (MainThread) [insteonplm.plm] Starting _handle_all_link_record_response
2018-03-17 08:35:05 INFO (MainThread) [insteonplm.plm] Found all link record for device 237d6e
2018-03-17 08:35:05 DEBUG (MainThread) [insteonplm.plm] Product data: address 237d6e cat: 09 subcat: 0b product_key: 3a
2018-03-17 08:35:05 DEBUG (MainThread) [insteonplm.devices.devicebase] Registering DeviceBase._receive_message callbacks for device 23.7D.6E
2018-03-17 08:35:05 DEBUG (MainThread) [insteonplm.aldb] New INSTEON Device '237d6e': SwitchLinc Switch (02:2a)
2018-03-17 08:35:05 INFO (MainThread) [homeassistant.components.insteon_plm] New INSTEON PLM device: 237d6e (lightOnOff) switch
2018-03-17 08:35:05 INFO (MainThread) [insteonplm.plm] Device with id 237d6e added to device list from ALDB data.
2018-03-17 08:35:05 DEBUG (MainThread) [insteonplm.plm] Starting: _get_next_all_link_recor
2018-03-17 08:35:05 INFO (MainThread) [insteonplm.plm] Requesting Next All-Link Record
2018-03-17 08:35:05 DEBUG (MainThread) [insteonplm.plm] Starting: send_msg
2018-03-17 08:35:05 DEBUG (MainThread) [insteonplm.plm] Ending: send_msg
2018-03-17 08:35:05 DEBUG (MainThread) [insteonplm.plm] Ending: _get_next_all_link_recor
2018-03-17 08:35:05 DEBUG (MainThread) [insteonplm.plm] Ending _handle_all_link_record_response
2018-03-17 08:35:05 DEBUG (MainThread) [insteonplm.plm] True
2018-03-17 08:35:05 DEBUG (MainThread) [insteonplm.plm] Writing message: {'code': 0x6a, 'acknak': None}
2018-03-17 08:35:05 DEBUG (MainThread) [insteonplm.states.stateBase] Registered callback for state: leakSensor
2018-03-17 08:35:05 DEBUG (MainThread) [insteonplm.plm] Starting _get_from_send_queue
2018-03-17 08:35:05 DEBUG (MainThread) [insteonplm.plm] Ending _get_from_send_queue
2018-03-17 08:35:06 DEBUG (MainThread) [insteonplm.plm] Starting: data_received
2018-03-17 08:35:06 DEBUG (MainThread) [insteonplm.plm] Received 13 bytes from PLM: b'026a060257a2fb237d6e090b3a'
2018-03-17 08:35:06 DEBUG (MainThread) [insteonplm.plm] Total buffer: b'026a060257a2fb237d6e090b3a'
2018-03-17 08:35:06 DEBUG (MainThread) [insteonplm.plm] Starting: _peel_messages_from_buffer
2018-03-17 08:35:06 DEBUG (MainThread) [insteonplm.plm] Finishing: _peel_messages_from_buffer
2018-03-17 08:35:06 DEBUG (MainThread) [insteonplm.plm] Messages in queue: 2
2018-03-17 08:35:06 DEBUG (MainThread) [insteonplm.plm] Processing message {'code': 0x6a, 'acknak': 0x06}
2018-03-17 08:35:06 DEBUG (MainThread) [insteonplm.plm] No callback found for message {'code': 0x6a, 'acknak': 0x06}
2018-03-17 08:35:06 DEBUG (MainThread) [insteonplm.plm] Processing message {'code': 0x57, 'controlFlags': 0xa2, 'group': 0xfb, 'address': 23.7D.6E, 'linkdata1': 0x09, 'linkdata2': 0x0b, 'linkdata3': 0x3a}
2018-03-17 08:35:06 DEBUG (MainThread) [insteonplm.plm] Calling method <function PLM._handle_all_link_record_response at 0x733506a8>
2018-03-17 08:35:06 DEBUG (MainThread) [insteonplm.plm] Last item in self._recv_queue reached.
2018-03-17 08:35:06 DEBUG (MainThread) [insteonplm.plm] Finishing: data_received
2018-03-17 08:35:06 DEBUG (MainThread) [insteonplm.plm] Starting _handle_all_link_record_response
2018-03-17 08:35:06 INFO (MainThread) [insteonplm.plm] Found all link record for device 237d6e
2018-03-17 08:35:06 DEBUG (MainThread) [insteonplm.plm] Starting: _get_next_all_link_recor
2018-03-17 08:35:06 INFO (MainThread) [insteonplm.plm] Requesting Next All-Link Record
2018-03-17 08:35:06 DEBUG (MainThread) [insteonplm.plm] Starting: send_msg
2018-03-17 08:35:06 DEBUG (MainThread) [insteonplm.plm] Ending: send_msg
2018-03-17 08:35:06 DEBUG (MainThread) [insteonplm.plm] Ending: _get_next_all_link_recor
2018-03-17 08:35:06 DEBUG (MainThread) [insteonplm.plm] Ending _handle_all_link_record_response
2018-03-17 08:35:06 DEBUG (MainThread) [insteonplm.plm] True
2018-03-17 08:35:06 DEBUG (MainThread) [insteonplm.plm] Writing message: {'code': 0x6a, 'acknak': None}
2018-03-17 08:35:07 DEBUG (MainThread) [insteonplm.plm] Starting: data_received
2018-03-17 08:35:07 DEBUG (MainThread) [insteonplm.plm] Received 13 bytes from PLM: b'026a060257a201237d6e090b3a'
2018-03-17 08:35:07 DEBUG (MainThread) [insteonplm.plm] Total buffer: b'026a060257a201237d6e090b3a'
2018-03-17 08:35:07 DEBUG (MainThread) [insteonplm.plm] Starting: _peel_messages_from_buffer
2018-03-17 08:35:07 DEBUG (MainThread) [insteonplm.plm] Finishing: _peel_messages_from_buffer
2018-03-17 08:35:07 DEBUG (MainThread) [insteonplm.plm] Messages in queue: 2
2018-03-17 08:35:07 DEBUG (MainThread) [insteonplm.plm] Processing message {'code': 0x6a, 'acknak': 0x06}
2018-03-17 08:35:07 DEBUG (MainThread) [insteonplm.plm] No callback found for message {'code': 0x6a, 'acknak': 0x06}
2018-03-17 08:35:07 DEBUG (MainThread) [insteonplm.plm] Processing message {'code': 0x57, 'controlFlags': 0xa2, 'group': 0x01, 'address': 23.7D.6E, 'linkdata1': 0x09, 'linkdata2': 0x0b, 'linkdata3': 0x3a}
2018-03-17 08:35:07 DEBUG (MainThread) [insteonplm.plm] Calling method <function PLM._handle_all_link_record_response at 0x733506a8>
2018-03-17 08:35:07 DEBUG (MainThread) [insteonplm.plm] Last item in self._recv_queue reached.
2018-03-17 08:35:07 DEBUG (MainThread) [insteonplm.plm] Finishing: data_received
2018-03-17 08:35:07 DEBUG (MainThread) [insteonplm.plm] Starting _get_from_send_queue
2018-03-17 08:35:07 DEBUG (MainThread) [insteonplm.plm] Ending _get_from_send_queue
2018-03-17 08:35:07 DEBUG (MainThread) [insteonplm.plm] Starting _handle_all_link_record_response
2018-03-17 08:35:07 INFO (MainThread) [insteonplm.plm] Found all link record for device 237d6e
2018-03-17 08:35:07 DEBUG (MainThread) [insteonplm.plm] Starting: _get_next_all_link_recor
2018-03-17 08:35:07 INFO (MainThread) [insteonplm.plm] Requesting Next All-Link Record
2018-03-17 08:35:07 DEBUG (MainThread) [insteonplm.plm] Starting: send_msg
2018-03-17 08:35:07 DEBUG (MainThread) [insteonplm.plm] Ending: send_msg
2018-03-17 08:35:07 DEBUG (MainThread) [insteonplm.plm] Ending: _get_next_all_link_recor
2018-03-17 08:35:07 DEBUG (MainThread) [insteonplm.plm] Ending _handle_all_link_record_response
2018-03-17 08:35:07 DEBUG (MainThread) [insteonplm.plm] True
2018-03-17 08:35:07 DEBUG (MainThread) [insteonplm.plm] Writing message: {'code': 0x6a, 'acknak': None}
2018-03-17 08:35:07 DEBUG (MainThread) [insteonplm.plm] Starting _get_from_send_queue
2018-03-17 08:35:07 DEBUG (MainThread) [insteonplm.plm] Ending _get_from_send_queue
2018-03-17 08:35:08 DEBUG (MainThread) [insteonplm.plm] Starting: data_received
2018-03-17 08:35:08 DEBUG (MainThread) [insteonplm.plm] Received 13 bytes from PLM: b'026a060257a201403ffc100844'
2018-03-17 08:35:08 DEBUG (MainThread) [insteonplm.plm] Total buffer: b'026a060257a201403ffc100844'
2018-03-17 08:35:08 DEBUG (MainThread) [insteonplm.plm] Starting: _peel_messages_from_buffer
2018-03-17 08:35:08 DEBUG (MainThread) [insteonplm.plm] Finishing: _peel_messages_from_buffer
2018-03-17 08:35:08 DEBUG (MainThread) [insteonplm.plm] Messages in queue: 2
2018-03-17 08:35:08 DEBUG (MainThread) [insteonplm.plm] Processing message {'code': 0x6a, 'acknak': 0x06}
2018-03-17 08:35:08 DEBUG (MainThread) [insteonplm.plm] No callback found for message {'code': 0x6a, 'acknak': 0x06}
2018-03-17 08:35:08 DEBUG (MainThread) [insteonplm.plm] Processing message {'code': 0x57, 'controlFlags': 0xa2, 'group': 0x01, 'address': 40.3F.FC, 'linkdata1': 0x10, 'linkdata2': 0x08, 'linkdata3': 0x44}
2018-03-17 08:35:08 DEBUG (MainThread) [insteonplm.plm] Calling method <function PLM._handle_all_link_record_response at 0x733506a8>
2018-03-17 08:35:08 DEBUG (MainThread) [insteonplm.plm] Last item in self._recv_queue reached.
2018-03-17 08:35:08 DEBUG (MainThread) [insteonplm.plm] Finishing: data_received

Madelinot · March 17, 2018, 12:48pm

Last, part 3:

2018-03-17 08:35:08 DEBUG (MainThread) [insteonplm.plm] Starting _handle_all_link_record_response
2018-03-17 08:35:08 INFO (MainThread) [insteonplm.plm] Found all link record for device 403ffc
2018-03-17 08:35:08 DEBUG (MainThread) [insteonplm.plm] Starting: _get_next_all_link_recor
2018-03-17 08:35:08 INFO (MainThread) [insteonplm.plm] Requesting Next All-Link Record
2018-03-17 08:35:08 DEBUG (MainThread) [insteonplm.plm] Starting: send_msg
2018-03-17 08:35:08 DEBUG (MainThread) [insteonplm.plm] Ending: send_msg
2018-03-17 08:35:08 DEBUG (MainThread) [insteonplm.plm] Ending: _get_next_all_link_recor
2018-03-17 08:35:08 DEBUG (MainThread) [insteonplm.plm] Ending _handle_all_link_record_response
2018-03-17 08:35:08 DEBUG (MainThread) [insteonplm.plm] True
2018-03-17 08:35:08 DEBUG (MainThread) [insteonplm.plm] Writing message: {'code': 0x6a, 'acknak': None}
2018-03-17 08:35:08 DEBUG (MainThread) [insteonplm.plm] Starting _get_from_send_queue
2018-03-17 08:35:08 DEBUG (MainThread) [insteonplm.plm] Ending _get_from_send_queue
2018-03-17 08:35:09 DEBUG (MainThread) [insteonplm.plm] Starting: data_received
2018-03-17 08:35:09 DEBUG (MainThread) [insteonplm.plm] Received 13 bytes from PLM: b'026a060257e200237d6e010000'
2018-03-17 08:35:09 DEBUG (MainThread) [insteonplm.plm] Total buffer: b'026a060257e200237d6e010000'
2018-03-17 08:35:09 DEBUG (MainThread) [insteonplm.plm] Starting: _peel_messages_from_buffer
2018-03-17 08:35:09 DEBUG (MainThread) [insteonplm.plm] Finishing: _peel_messages_from_buffer
2018-03-17 08:35:09 DEBUG (MainThread) [insteonplm.plm] Messages in queue: 2
2018-03-17 08:35:09 DEBUG (MainThread) [insteonplm.plm] Processing message {'code': 0x6a, 'acknak': 0x06}
2018-03-17 08:35:09 DEBUG (MainThread) [insteonplm.plm] No callback found for message {'code': 0x6a, 'acknak': 0x06}
2018-03-17 08:35:09 DEBUG (MainThread) [insteonplm.plm] Processing message {'code': 0x57, 'controlFlags': 0xe2, 'group': 0x00, 'address': 23.7D.6E, 'linkdata1': 0x01, 'linkdata2': 0x00, 'linkdata3': 0x00}
2018-03-17 08:35:09 DEBUG (MainThread) [insteonplm.plm] Calling method <function PLM._handle_all_link_record_response at 0x733506a8>
2018-03-17 08:35:09 DEBUG (MainThread) [insteonplm.plm] Last item in self._recv_queue reached.
2018-03-17 08:35:09 DEBUG (MainThread) [insteonplm.plm] Finishing: data_received
2018-03-17 08:35:09 DEBUG (MainThread) [insteonplm.plm] Starting _handle_all_link_record_response
2018-03-17 08:35:09 INFO (MainThread) [insteonplm.plm] Found all link record for device 237d6e
2018-03-17 08:35:09 DEBUG (MainThread) [insteonplm.plm] Starting: _get_next_all_link_recor
2018-03-17 08:35:09 INFO (MainThread) [insteonplm.plm] Requesting Next All-Link Record
2018-03-17 08:35:09 DEBUG (MainThread) [insteonplm.plm] Starting: send_msg
2018-03-17 08:35:09 DEBUG (MainThread) [insteonplm.plm] Ending: send_msg
2018-03-17 08:35:09 DEBUG (MainThread) [insteonplm.plm] Ending: _get_next_all_link_recor
2018-03-17 08:35:09 DEBUG (MainThread) [insteonplm.plm] Ending _handle_all_link_record_response
2018-03-17 08:35:09 DEBUG (MainThread) [insteonplm.plm] True
2018-03-17 08:35:09 DEBUG (MainThread) [insteonplm.plm] Writing message: {'code': 0x6a, 'acknak': None}
2018-03-17 08:35:09 DEBUG (MainThread) [insteonplm.plm] Starting _get_from_send_queue
2018-03-17 08:35:09 DEBUG (MainThread) [insteonplm.plm] Ending _get_from_send_queue
2018-03-17 08:35:09 DEBUG (MainThread) [homeassistant.components.switch.insteon_plm] Adding device 237d6e entity lightOnOff to Switch platform
2018-03-17 08:35:10 DEBUG (MainThread) [insteonplm.plm] Starting: data_received
2018-03-17 08:35:10 DEBUG (MainThread) [insteonplm.plm] Received 13 bytes from PLM: b'026a060257e2012d60ac100841'
2018-03-17 08:35:10 DEBUG (MainThread) [insteonplm.plm] Total buffer: b'026a060257e2012d60ac100841'
2018-03-17 08:35:10 DEBUG (MainThread) [insteonplm.plm] Starting: _peel_messages_from_buffer
2018-03-17 08:35:10 DEBUG (MainThread) [insteonplm.plm] Finishing: _peel_messages_from_buffer
2018-03-17 08:35:10 DEBUG (MainThread) [insteonplm.plm] Messages in queue: 2
2018-03-17 08:35:10 DEBUG (MainThread) [insteonplm.plm] Processing message {'code': 0x6a, 'acknak': 0x06}
2018-03-17 08:35:10 DEBUG (MainThread) [insteonplm.plm] No callback found for message {'code': 0x6a, 'acknak': 0x06}
2018-03-17 08:35:10 DEBUG (MainThread) [insteonplm.plm] Processing message {'code': 0x57, 'controlFlags': 0xe2, 'group': 0x01, 'address': 2D.60.AC, 'linkdata1': 0x10, 'linkdata2': 0x08, 'linkdata3': 0x41}
2018-03-17 08:35:10 DEBUG (MainThread) [insteonplm.plm] Calling method <function PLM._handle_all_link_record_response at 0x733506a8>
2018-03-17 08:35:10 DEBUG (MainThread) [insteonplm.plm] Last item in self._recv_queue reached.
2018-03-17 08:35:10 DEBUG (MainThread) [insteonplm.plm] Finishing: data_received
2018-03-17 08:35:10 DEBUG (MainThread) [insteonplm.plm] Starting _handle_all_link_record_response
2018-03-17 08:35:10 INFO (MainThread) [insteonplm.plm] Found all link record for device 2d60ac
2018-03-17 08:35:10 DEBUG (MainThread) [insteonplm.plm] Starting: _get_next_all_link_recor
2018-03-17 08:35:10 INFO (MainThread) [insteonplm.plm] Requesting Next All-Link Record
2018-03-17 08:35:10 DEBUG (MainThread) [insteonplm.plm] Starting: send_msg
2018-03-17 08:35:10 DEBUG (MainThread) [insteonplm.plm] Ending: send_msg
2018-03-17 08:35:10 DEBUG (MainThread) [insteonplm.plm] Ending: _get_next_all_link_recor
2018-03-17 08:35:10 DEBUG (MainThread) [insteonplm.plm] Ending _handle_all_link_record_response
2018-03-17 08:35:10 DEBUG (MainThread) [insteonplm.plm] True
2018-03-17 08:35:10 DEBUG (MainThread) [insteonplm.plm] Writing message: {'code': 0x6a, 'acknak': None}
2018-03-17 08:35:10 DEBUG (MainThread) [insteonplm.states.stateBase] Registered callback for state: lightOnOff
2018-03-17 08:35:10 DEBUG (MainThread) [insteonplm.plm] Starting _get_from_send_queue
2018-03-17 08:35:10 DEBUG (MainThread) [insteonplm.plm] Ending _get_from_send_queue
2018-03-17 08:35:11 DEBUG (MainThread) [insteonplm.plm] Starting: data_received
2018-03-17 08:35:11 DEBUG (MainThread) [insteonplm.plm] Received 13 bytes from PLM: b'026a060257a2012857be100841'
2018-03-17 08:35:11 DEBUG (MainThread) [insteonplm.plm] Total buffer: b'026a060257a2012857be100841'
2018-03-17 08:35:11 DEBUG (MainThread) [insteonplm.plm] Starting: _peel_messages_from_buffer
2018-03-17 08:35:11 DEBUG (MainThread) [insteonplm.plm] Finishing: _peel_messages_from_buffer
2018-03-17 08:35:11 DEBUG (MainThread) [insteonplm.plm] Messages in queue: 2
2018-03-17 08:35:11 DEBUG (MainThread) [insteonplm.plm] Processing message {'code': 0x6a, 'acknak': 0x06}
2018-03-17 08:35:11 DEBUG (MainThread) [insteonplm.plm] No callback found for message {'code': 0x6a, 'acknak': 0x06}
2018-03-17 08:35:11 DEBUG (MainThread) [insteonplm.plm] Processing message {'code': 0x57, 'controlFlags': 0xa2, 'group': 0x01, 'address': 28.57.BE, 'linkdata1': 0x10, 'linkdata2': 0x08, 'linkdata3': 0x41}
2018-03-17 08:35:11 DEBUG (MainThread) [insteonplm.plm] Calling method <function PLM._handle_all_link_record_response at 0x733506a8>
2018-03-17 08:35:11 DEBUG (MainThread) [insteonplm.plm] Last item in self._recv_queue reached.
2018-03-17 08:35:11 DEBUG (MainThread) [insteonplm.plm] Finishing: data_received
2018-03-17 08:35:11 DEBUG (MainThread) [insteonplm.plm] Starting _handle_all_link_record_response
2018-03-17 08:35:11 INFO (MainThread) [insteonplm.plm] Found all link record for device 2857be
2018-03-17 08:35:11 DEBUG (MainThread) [insteonplm.plm] Starting: _get_next_all_link_recor
2018-03-17 08:35:11 INFO (MainThread) [insteonplm.plm] Requesting Next All-Link Record
2018-03-17 08:35:11 DEBUG (MainThread) [insteonplm.plm] Starting: send_msg
2018-03-17 08:35:11 DEBUG (MainThread) [insteonplm.plm] Ending: send_msg
2018-03-17 08:35:11 DEBUG (MainThread) [insteonplm.plm] Ending: _get_next_all_link_recor
2018-03-17 08:35:11 DEBUG (MainThread) [insteonplm.plm] Ending _handle_all_link_record_response
2018-03-17 08:35:11 DEBUG (MainThread) [insteonplm.plm] True
2018-03-17 08:35:11 DEBUG (MainThread) [insteonplm.plm] Writing message: {'code': 0x6a, 'acknak': None}
2018-03-17 08:35:12 DEBUG (MainThread) [insteonplm.plm] Starting _get_from_send_queue
2018-03-17 08:35:12 DEBUG (MainThread) [insteonplm.plm] Ending _get_from_send_queue
2018-03-17 08:35:13 DEBUG (MainThread) [insteonplm.plm] Starting: data_received
2018-03-17 08:35:13 DEBUG (MainThread) [insteonplm.plm] Received 13 bytes from PLM: b'026a060257a2014038bb100844'
2018-03-17 08:35:13 DEBUG (MainThread) [insteonplm.plm] Total buffer: b'026a060257a2014038bb100844'
2018-03-17 08:35:13 DEBUG (MainThread) [insteonplm.plm] Starting: _peel_messages_from_buffer
2018-03-17 08:35:13 DEBUG (MainThread) [insteonplm.plm] Finishing: _peel_messages_from_buffer
2018-03-17 08:35:13 DEBUG (MainThread) [insteonplm.plm] Messages in queue: 2
2018-03-17 08:35:13 DEBUG (MainThread) [insteonplm.plm] Processing message {'code': 0x6a, 'acknak': 0x06}
2018-03-17 08:35:13 DEBUG (MainThread) [insteonplm.plm] No callback found for message {'code': 0x6a, 'acknak': 0x06}
2018-03-17 08:35:13 DEBUG (MainThread) [insteonplm.plm] Processing message {'code': 0x57, 'controlFlags': 0xa2, 'group': 0x01, 'address': 40.38.BB, 'linkdata1': 0x10, 'linkdata2': 0x08, 'linkdata3': 0x44}
2018-03-17 08:35:13 DEBUG (MainThread) [insteonplm.plm] Calling method <function PLM._handle_all_link_record_response at 0x733506a8>
2018-03-17 08:35:13 DEBUG (MainThread) [insteonplm.plm] Last item in self._recv_queue reached.
2018-03-17 08:35:13 DEBUG (MainThread) [insteonplm.plm] Finishing: data_received
2018-03-17 08:35:13 DEBUG (MainThread) [insteonplm.plm] Starting _handle_all_link_record_response
2018-03-17 08:35:13 INFO (MainThread) [insteonplm.plm] Found all link record for device 4038bb
2018-03-17 08:35:13 DEBUG (MainThread) [insteonplm.plm] Starting: _get_next_all_link_recor
2018-03-17 08:35:13 INFO (MainThread) [insteonplm.plm] Requesting Next All-Link Record
2018-03-17 08:35:13 DEBUG (MainThread) [insteonplm.plm] Starting: send_msg
2018-03-17 08:35:13 DEBUG (MainThread) [insteonplm.plm] Ending: send_msg
2018-03-17 08:35:13 DEBUG (MainThread) [insteonplm.plm] Ending: _get_next_all_link_recor
2018-03-17 08:35:13 DEBUG (MainThread) [insteonplm.plm] Ending _handle_all_link_record_response
2018-03-17 08:35:13 DEBUG (MainThread) [insteonplm.plm] True
2018-03-17 08:35:13 DEBUG (MainThread) [insteonplm.plm] Writing message: {'code': 0x6a, 'acknak': None}
2018-03-17 08:35:13 DEBUG (MainThread) [insteonplm.plm] Starting _get_from_send_queue
2018-03-17 08:35:13 DEBUG (MainThread) [insteonplm.plm] Ending _get_from_send_queue
2018-03-17 08:35:14 DEBUG (MainThread) [insteonplm.plm] Starting: data_received
2018-03-17 08:35:14 DEBUG (MainThread) [insteonplm.plm] Received 3 bytes from PLM: b'026a15'
2018-03-17 08:35:14 DEBUG (MainThread) [insteonplm.plm] Total buffer: b'026a15'
2018-03-17 08:35:14 DEBUG (MainThread) [insteonplm.plm] Starting: _peel_messages_from_buffer
2018-03-17 08:35:14 DEBUG (MainThread) [insteonplm.plm] Finishing: _peel_messages_from_buffer
2018-03-17 08:35:14 DEBUG (MainThread) [insteonplm.plm] Messages in queue: 1
2018-03-17 08:35:14 DEBUG (MainThread) [insteonplm.plm] Processing message {'code': 0x6a, 'acknak': 0x15}
2018-03-17 08:35:14 DEBUG (MainThread) [insteonplm.plm] Calling method <function PLM._handle_get_next_all_link_record_nak at 0x733506f0>
2018-03-17 08:35:14 DEBUG (MainThread) [insteonplm.plm] Last item in self._recv_queue reached.
2018-03-17 08:35:14 DEBUG (MainThread) [insteonplm.plm] Finishing: data_received
2018-03-17 08:35:14 DEBUG (MainThread) [insteonplm.plm] Starting _handle_get_next_all_link_record_nak
2018-03-17 08:35:14 DEBUG (MainThread) [insteonplm.plm] All-Link device records found in ALDB: 0
2018-03-17 08:35:14 DEBUG (MainThread) [insteonplm.plm] Ending _handle_get_next_all_link_record_nak
2018-03-17 08:35:14 DEBUG (MainThread) [insteonplm.plm] True
2018-03-17 08:35:14 DEBUG (MainThread) [insteonplm.plm] Writing 6 devices to save file
2018-03-17 08:35:14 DEBUG (MainThread) [insteonplm.plm] Starting: poll_devices
2018-03-17 08:35:14 DEBUG (MainThread) [insteonplm.plm] Ending: poll_devices
2018-03-17 08:35:15 DEBUG (MainThread) [insteonplm.states.stateBase] Starting OnOffSwitch._send_status_request
2018-03-17 08:35:15 DEBUG (MainThread) [insteonplm.devices.devicebase] Starting DeviceBase._send_msg
2018-03-17 08:35:15 DEBUG (MainThread) [insteonplm.devices.devicebase] Ending DeviceBase._send_msg
2018-03-17 08:35:15 DEBUG (MainThread) [insteonplm.devices.devicebase] Starting DeviceBase._process_send_queue
2018-03-17 08:35:15 DEBUG (MainThread) [insteonplm.devices.devicebase] Attempt to acquire lock
2018-03-17 08:35:15 DEBUG (MainThread) [insteonplm.devices.devicebase] Lock acquired
2018-03-17 08:35:15 DEBUG (MainThread) [insteonplm.plm] Starting: send_msg
2018-03-17 08:35:15 DEBUG (MainThread) [insteonplm.plm] Ending: send_msg
2018-03-17 08:35:15 DEBUG (MainThread) [insteonplm.devices.devicebase] Ending DeviceBase._process_send_queue
2018-03-17 08:35:15 DEBUG (MainThread) [insteonplm.plm] Writing message: {'code': 0x62, 'address': 23.7D.6E, 'flags': 0x00, 'cmd1': 0x19, 'cmd2': 0x00, 'acknak': None}
2018-03-17 08:35:15 DEBUG (MainThread) [insteonplm.plm] Starting _get_from_send_queue
2018-03-17 08:35:15 DEBUG (MainThread) [insteonplm.plm] Ending _get_from_send_queue
2018-03-17 08:35:16 DEBUG (MainThread) [insteonplm.plm] Starting: data_received
2018-03-17 08:35:16 DEBUG (MainThread) [insteonplm.plm] Received 20 bytes from PLM: b'0262237d6e001900060250237d6e49f8ee2013ff'
2018-03-17 08:35:16 DEBUG (MainThread) [insteonplm.plm] Total buffer: b'0262237d6e001900060250237d6e49f8ee2013ff'
2018-03-17 08:35:16 DEBUG (MainThread) [insteonplm.plm] Starting: _peel_messages_from_buffer
2018-03-17 08:35:16 DEBUG (MainThread) [insteonplm.plm] Finishing: _peel_messages_from_buffer
2018-03-17 08:35:16 DEBUG (MainThread) [insteonplm.plm] Messages in queue: 2
2018-03-17 08:35:16 DEBUG (MainThread) [insteonplm.plm] Processing message {'code': 0x62, 'address': 23.7D.6E, 'flags': 0x00, 'cmd1': 0x19, 'cmd2': 0x00, 'acknak': 0x06}
2018-03-17 08:35:16 DEBUG (MainThread) [insteonplm.plm] Calling method <function DeviceBase._receive_message at 0x73341c48>
2018-03-17 08:35:16 DEBUG (MainThread) [insteonplm.plm] Processing message {'code': 0x50, 'address': 23.7D.6E, 'target': 49.F8.EE, 'flags': 0x20, 'cmd1': 0x13, 'cmd2': 0xff}
2018-03-17 08:35:16 DEBUG (MainThread) [insteonplm.plm] Calling method <function DeviceBase._receive_message at 0x73341c48>
2018-03-17 08:35:16 DEBUG (MainThread) [insteonplm.plm] Last item in self._recv_queue reached.
2018-03-17 08:35:16 DEBUG (MainThread) [insteonplm.plm] Finishing: data_received
2018-03-17 08:35:16 DEBUG (MainThread) [insteonplm.devices.devicebase] Starting DeviceBase._receive_message
2018-03-17 08:35:16 DEBUG (MainThread) [insteonplm.devices.devicebase] Got Message ACK
2018-03-17 08:35:16 DEBUG (MainThread) [insteonplm.devices.devicebase] Look for direct ACK
2018-03-17 08:35:16 DEBUG (MainThread) [insteonplm.devices.devicebase] Ending DeviceBase._receive_message
2018-03-17 08:35:16 DEBUG (MainThread) [insteonplm.devices.devicebase] Starting DeviceBase._receive_message
2018-03-17 08:35:16 DEBUG (MainThread) [insteonplm.devices.devicebase] Got Direct ACK message
2018-03-17 08:35:16 DEBUG (MainThread) [insteonplm.devices.devicebase] Lock is locked
2018-03-17 08:35:16 DEBUG (MainThread) [insteonplm.devices.devicebase] Ending DeviceBase._receive_message
2018-03-17 08:35:16 DEBUG (MainThread) [insteonplm.plm] True
2018-03-17 08:35:16 DEBUG (MainThread) [insteonplm.devices.devicebase] Starting DeviceBase._wait_for_direct_ACK
2018-03-17 08:35:16 DEBUG (MainThread) [insteonplm.devices.devicebase] Releasing lock
2018-03-17 08:35:16 DEBUG (MainThread) [insteonplm.states.stateBase] Starting OnOffSwitch._status_message_received
2018-03-17 08:35:16 DEBUG (MainThread) [insteonplm.states.stateBase] Ending OnOffSwitch._status_message_received
2018-03-17 08:35:16 DEBUG (MainThread) [insteonplm.devices.devicebase] Ending DeviceBase._wait_for_direct_ACK
2018-03-17 08:35:18 DEBUG (MainThread) [insteonplm.plm] No new messages received.
2018-03-17 08:35:18 DEBUG (MainThread) [insteonplm.plm] Ending _get_from_send_queue
2018-03-17 08:35:20 ERROR (MainThread) [homeassistant.components.sensor] systemmonitor: Error on device update!

teharris1 · March 17, 2018, 1:46pm

I won’t be able to look at this until tonight but let it run overnight and post the remaining log tomorrow if you can. Regarding the devices going to ‘off’, this is because they are battery operated and there is no way to query them. You have to wait for them to send an update. They send updates at least once per day which is why I need tomorrow’s log.

roborob · March 17, 2018, 8:25pm

First all the positive stuff from the latest changes:

I’m certainly glad to see work on the Insteon PLM components. I wasn’t quite so glad at first when I read the release notes after almost all my Insteon components broke, but I’ve got almost everything working with device overrides in the new form. (I had had to override all my wireless device definitions, since they were showing up as whatever device was relaying them to the PLM at the time. So, e.g., a motion detector would show up as an outdoor on/off modules if the closest dual band device to it was an on/off module.)

Anyway, reliability seems better now. I don’t have to do 2 or three resets in a row every so often to get most devices to show up. (And the devices on circuits with way too much noise are a little more usable as well.)

Now the problem:

Here’s the thing I haven’t got sorted out yet – my wireless motion sensors just appear as red circles with dashes and labeled with the insteon address. The device history appears as “Unknown”. If I override with cat: 0x10 and subcat 0x16, the result is the same. (I got the cat and subcat from the motion sensor manual, so I assume that those are right . . .) The wireless leak detector seems to show up as expected.

Here’s what I put in the configutation file “insteon_plm” section for my wireless sensors:

(Hopefully I figured out the code block syntax for this form, if not, I’m sorry, it’s my “first day on the job”.)

#    - address: 45FAA8 # Insteon motion sensor Back Door
#      cat: 0x10
#      subcat: 0x16

    - address: 321D21 #Insteon leak detector
      cat: 0x10
      subcat: 0x08 # 915MHz RF frequency

#    - address: 4168CB # Insteon motion sensor Front Door
#      cat: 0x10
#      subcat: 0x16

I’ve tried it with and without the motion sensor entries commented out.

Any ideas?

Thanks again for the continued work on Insteon PLM

(edit 1, re-do the formatting)

teharris1 · March 18, 2018, 1:23am

@roborob for the motion sensor, is it showing up as a Generic Security, Heath and Safety Device? If not then I need to look closer. If it is, the issue you are seeing is that the specific device is not in the IPDB. I can add for the next release (probably in about 4-6 weeks). In the mean time, use cat 0x10, subcat 0x01. That is for the 2842-222 motion sensor. I assume yours is 2844-222. The 2844-222 was just released last year and there is almost no documentation on it.

Cat 0x10 devices are problematic in general because they are battery operated and because some are variable value sensors while others are binary sensors. I made the default a variable sensor because it is more versatile but at the expense of not being as pretty.

teharris1 · March 18, 2018, 1:27am

@roborob one of the things that would help me develop for your specific device would be a dump of the messages to and from the device. The way to do that is to grep the home-assistant.log file while the module is in debug mode. So if your device address is 1A.2B.3C, use the following command form your HA config directory:

cat home-assistant.log | grep -E '1A.2B.3C|1a2b3c'

That will give me all of the messages for your device that I can code to. Leave it run for at least 24 hours before running that command.

teharris1 · March 18, 2018, 1:37am

@Madelinot I don’t see any indication that your PLM has issues so I have to assume the problem is the bug I noted previously. If that is the case, I am not sure how to help since i have not figured out how to work around the current issue. I am hoping i can fix it in the next release (4-6 weeks). In the mean time, try to restart your system when you can be sure the devices are not sending commands (i.e. don’t turn on a light or open a door for the first minute that the system is starting up). Not ideal but hopefully not the worst thing in the world.

Do send the remaining log tomorrow though so I can check the on/off messages and make sure the device is coded correctly.

roborob · March 18, 2018, 2:43am

The motion sensor is indeed showing up as "Generic Security . . . " It is the newer motion sensor (2844) with the lithium cell and optional USB power. I’ll give the cat and subcat values you give a try.

I started debug mode about 1600 (UTC-5), so I’ll let it run till sometime tomorrow and post the messages from both affected devices. I’ve got two of them, so there will be plenty of data – just walking by the one temporarily located by the Pi generated a few dozen lines of log.

Thanks for looking into this. I’m happy to help with any testing you need.

roborob · March 18, 2018, 9:42pm

I was gonna post my log, but there’s a 32000 char limit for posts, and my log is almost 100k. Is splitting up the log into 3 or 4 posts the way to go, or is there a way to upload a text document instead?

Madelinot · March 19, 2018, 11:53am

Hi,

I let it ran for a day now, some leak sensors are still showing as wet (3 out of 5). I have this in my logs:

2018-03-18 15:51:27 DEBUG (MainThread) [insteonplm.plm] Starting: data_received
2018-03-18 15:51:27 DEBUG (MainThread) [insteonplm.plm] Received 22 bytes from PLM: b'0250284f3d000004cf11040250284f3d000004cf1104'
2018-03-18 15:51:27 DEBUG (MainThread) [insteonplm.plm] Total buffer: b'0250284f3d000004cf11040250284f3d000004cf1104'
2018-03-18 15:51:27 DEBUG (MainThread) [insteonplm.plm] Starting: _peel_messages_from_buffer
2018-03-18 15:51:27 DEBUG (MainThread) [insteonplm.plm] Finishing: _peel_messages_from_buffer
2018-03-18 15:51:27 DEBUG (MainThread) [insteonplm.plm] Messages in queue: 2
2018-03-18 15:51:27 DEBUG (MainThread) [insteonplm.plm] Processing message {'code': 0x50, 'address': 28.4F.3D, 'target': 00.00.04, 'flags': 0xcf, 'cmd1': 0x11, 'cmd2': 0x04}
2018-03-18 15:51:27 DEBUG (MainThread) [insteonplm.plm] Calling method <function DeviceBase._receive_message at 0x734953d8>
2018-03-18 15:51:27 DEBUG (MainThread) [insteonplm.plm] Processing message {'code': 0x50, 'address': 28.4F.3D, 'target': 00.00.04, 'flags': 0xcf, 'cmd1': 0x11, 'cmd2': 0x04}
2018-03-18 15:51:27 DEBUG (MainThread) [insteonplm.plm] Calling method <function DeviceBase._receive_message at 0x734953d8>
2018-03-18 15:51:27 DEBUG (MainThread) [insteonplm.plm] Last item in self._recv_queue reached.
2018-03-18 15:51:27 DEBUG (MainThread) [insteonplm.plm] Finishing: data_received
2018-03-18 15:51:27 DEBUG (MainThread) [insteonplm.devices.devicebase] Starting DeviceBase._receive_message
2018-03-18 15:51:27 DEBUG (MainThread) [insteonplm.devices.devicebase] Ending DeviceBase._receive_message
2018-03-18 15:51:27 DEBUG (MainThread) [insteonplm.devices.devicebase] Starting DeviceBase._receive_message
2018-03-18 15:51:27 DEBUG (MainThread) [insteonplm.devices.devicebase] Ending DeviceBase._receive_message
2018-03-18 15:51:29 DEBUG (MainThread) [insteonplm.plm] Starting: data_received
2018-03-18 15:51:29 DEBUG (MainThread) [insteonplm.plm] Received 22 bytes from PLM: b'0250284f3d49f8ee4511040250284f3d110104cf0600'
2018-03-18 15:51:29 DEBUG (MainThread) [insteonplm.plm] Total buffer: b'0250284f3d49f8ee4511040250284f3d110104cf0600'
2018-03-18 15:51:29 DEBUG (MainThread) [insteonplm.plm] Starting: _peel_messages_from_buffer
2018-03-18 15:51:29 DEBUG (MainThread) [insteonplm.plm] Finishing: _peel_messages_from_buffer
2018-03-18 15:51:29 DEBUG (MainThread) [insteonplm.plm] Messages in queue: 2
2018-03-18 15:51:29 DEBUG (MainThread) [insteonplm.plm] Processing message {'code': 0x50, 'address': 28.4F.3D, 'target': 49.F8.EE, 'flags': 0x45, 'cmd1': 0x11, 'cmd2': 0x04}
2018-03-18 15:51:29 DEBUG (MainThread) [insteonplm.plm] Calling method <function DeviceBase._receive_message at 0x734953d8>
2018-03-18 15:51:29 DEBUG (MainThread) [insteonplm.plm] Calling method <function OnOffSensor._sensor_on_command_received at 0x7349ccd8>
2018-03-18 15:51:29 DEBUG (MainThread) [insteonplm.plm] Processing message {'code': 0x50, 'address': 28.4F.3D, 'target': 11.01.04, 'flags': 0xcf, 'cmd1': 0x06, 'cmd2': 0x00}
2018-03-18 15:51:29 DEBUG (MainThread) [insteonplm.plm] Calling method <function DeviceBase._receive_message at 0x734953d8>
2018-03-18 15:51:29 DEBUG (MainThread) [insteonplm.plm] Last item in self._recv_queue reached.
2018-03-18 15:51:29 DEBUG (MainThread) [insteonplm.plm] Finishing: data_received
2018-03-18 15:51:29 DEBUG (MainThread) [insteonplm.devices.devicebase] Starting DeviceBase._receive_message
2018-03-18 15:51:29 DEBUG (MainThread) [insteonplm.devices.devicebase] Ending DeviceBase._receive_message
2018-03-18 15:51:29 DEBUG (MainThread) [insteonplm.states.stateBase] Starting SecurityHealthSafety._sensor_on_command_received
2018-03-18 15:51:29 DEBUG (MainThread) [insteonplm.states.stateBase] Starting SecurityHealthSafety._sensor_on_command_received
2018-03-18 15:51:29 DEBUG (MainThread) [insteonplm.devices.devicebase] Starting DeviceBase._receive_message
2018-03-18 15:51:29 DEBUG (MainThread) [insteonplm.devices.devicebase] Ending DeviceBase._receive_message
2018-03-18 15:51:30 DEBUG (MainThread) [insteonplm.plm] Starting: data_received
2018-03-18 15:51:30 DEBUG (MainThread) [insteonplm.plm] Received 11 bytes from PLM: b'0250284f3d110104cf0600'
2018-03-18 15:51:30 DEBUG (MainThread) [insteonplm.plm] Total buffer: b'0250284f3d110104cf0600'
2018-03-18 15:51:30 DEBUG (MainThread) [insteonplm.plm] Starting: _peel_messages_from_buffer
2018-03-18 15:51:30 DEBUG (MainThread) [insteonplm.plm] Finishing: _peel_messages_from_buffer
2018-03-18 15:51:30 DEBUG (MainThread) [insteonplm.plm] Messages in queue: 1
2018-03-18 15:51:30 DEBUG (MainThread) [insteonplm.plm] Processing message {'code': 0x50, 'address': 28.4F.3D, 'target': 11.01.04, 'flags': 0xcf, 'cmd1': 0x06, 'cmd2': 0x00}
2018-03-18 15:51:30 DEBUG (MainThread) [insteonplm.plm] Calling method <function DeviceBase._receive_message at 0x734953d8>
2018-03-18 15:51:30 DEBUG (MainThread) [insteonplm.plm] Last item in self._recv_queue reached.
2018-03-18 15:51:30 DEBUG (MainThread) [insteonplm.plm] Finishing: data_received
2018-03-18 15:51:30 DEBUG (MainThread) [insteonplm.devices.devicebase] Starting DeviceBase._receive_message
2018-03-18 15:51:30 DEBUG (MainThread) [insteonplm.devices.devicebase] Ending DeviceBase._receive_message
2018-03-18 16:01:17 DEBUG (MainThread) [insteonplm.plm] Starting: data_received
2018-03-18 16:01:17 DEBUG (MainThread) [insteonplm.plm] Received 11 bytes from PLM: b'02502d60ac000004cb1104'
2018-03-18 16:01:17 DEBUG (MainThread) [insteonplm.plm] Total buffer: b'02502d60ac000004cb1104'
2018-03-18 16:01:17 DEBUG (MainThread) [insteonplm.plm] Starting: _peel_messages_from_buffer
2018-03-18 16:01:17 DEBUG (MainThread) [insteonplm.plm] Finishing: _peel_messages_from_buffer
2018-03-18 16:01:17 DEBUG (MainThread) [insteonplm.plm] Messages in queue: 1
2018-03-18 16:01:17 DEBUG (MainThread) [insteonplm.plm] Processing message {'code': 0x50, 'address': 2D.60.AC, 'target': 00.00.04, 'flags': 0xcb, 'cmd1': 0x11, 'cmd2': 0x04}
2018-03-18 16:01:17 DEBUG (MainThread) [insteonplm.plm] Calling method <function DeviceBase._receive_message at 0x734953d8>
2018-03-18 16:01:17 DEBUG (MainThread) [insteonplm.plm] Last item in self._recv_queue reached.
2018-03-18 16:01:17 DEBUG (MainThread) [insteonplm.plm] Finishing: data_received
2018-03-18 16:01:17 DEBUG (MainThread) [insteonplm.devices.devicebase] Starting DeviceBase._receive_message
2018-03-18 16:01:17 DEBUG (MainThread) [insteonplm.devices.devicebase] Ending DeviceBase._receive_message
2018-03-18 16:01:18 DEBUG (MainThread) [insteonplm.plm] Starting: data_received
2018-03-18 16:01:18 DEBUG (MainThread) [insteonplm.plm] Received 11 bytes from PLM: b'02502d60ac000004cb1104'
2018-03-18 16:01:18 DEBUG (MainThread) [insteonplm.plm] Total buffer: b'02502d60ac000004cb1104'
2018-03-18 16:01:18 DEBUG (MainThread) [insteonplm.plm] Starting: _peel_messages_from_buffer
2018-03-18 16:01:18 DEBUG (MainThread) [insteonplm.plm] Finishing: _peel_messages_from_buffer
2018-03-18 16:01:18 DEBUG (MainThread) [insteonplm.plm] Messages in queue: 1
2018-03-18 16:01:18 DEBUG (MainThread) [insteonplm.plm] Processing message {'code': 0x50, 'address': 2D.60.AC, 'target': 00.00.04, 'flags': 0xcb, 'cmd1': 0x11, 'cmd2': 0x04}
2018-03-18 16:01:18 DEBUG (MainThread) [insteonplm.plm] Calling method <function DeviceBase._receive_message at 0x734953d8>
2018-03-18 16:01:18 DEBUG (MainThread) [insteonplm.plm] Last item in self._recv_queue reached.
2018-03-18 16:01:18 DEBUG (MainThread) [insteonplm.plm] Finishing: data_received
2018-03-18 16:01:18 DEBUG (MainThread) [insteonplm.devices.devicebase] Starting DeviceBase._receive_message
2018-03-18 16:01:18 DEBUG (MainThread) [insteonplm.devices.devicebase] Ending DeviceBase._receive_message
2018-03-18 16:01:20 DEBUG (MainThread) [insteonplm.plm] Starting: data_received
2018-03-18 16:01:20 DEBUG (MainThread) [insteonplm.plm] Received 22 bytes from PLM: b'02502d60ac49f8ee4b110402502d60ac110104c70600'
2018-03-18 16:01:20 DEBUG (MainThread) [insteonplm.plm] Total buffer: b'02502d60ac49f8ee4b110402502d60ac110104c70600'
2018-03-18 16:01:20 DEBUG (MainThread) [insteonplm.plm] Starting: _peel_messages_from_buffer
2018-03-18 16:01:20 DEBUG (MainThread) [insteonplm.plm] Finishing: _peel_messages_from_buffer
2018-03-18 16:01:20 DEBUG (MainThread) [insteonplm.plm] Messages in queue: 2
2018-03-18 16:01:20 DEBUG (MainThread) [insteonplm.plm] Processing message {'code': 0x50, 'address': 2D.60.AC, 'target': 49.F8.EE, 'flags': 0x4b, 'cmd1': 0x11, 'cmd2': 0x04}
2018-03-18 16:01:20 DEBUG (MainThread) [insteonplm.plm] Calling method <function DeviceBase._receive_message at 0x734953d8>
2018-03-18 16:01:20 DEBUG (MainThread) [insteonplm.plm] Calling method <function OnOffSensor._sensor_on_command_received at 0x7349ccd8>
2018-03-18 16:01:20 DEBUG (MainThread) [insteonplm.plm] Processing message {'code': 0x50, 'address': 2D.60.AC, 'target': 11.01.04, 'flags': 0xc7, 'cmd1': 0x06, 'cmd2': 0x00}
2018-03-18 16:01:20 DEBUG (MainThread) [insteonplm.plm] Calling method <function DeviceBase._receive_message at 0x734953d8>
2018-03-18 16:01:20 DEBUG (MainThread) [insteonplm.plm] Last item in self._recv_queue reached.
2018-03-18 16:01:20 DEBUG (MainThread) [insteonplm.plm] Finishing: data_received
2018-03-18 16:01:20 DEBUG (MainThread) [insteonplm.devices.devicebase] Starting DeviceBase._receive_message
2018-03-18 16:01:20 DEBUG (MainThread) [insteonplm.devices.devicebase] Ending DeviceBase._receive_message
2018-03-18 16:01:20 DEBUG (MainThread) [insteonplm.states.stateBase] Starting SecurityHealthSafety._sensor_on_command_received
2018-03-18 16:01:20 DEBUG (MainThread) [insteonplm.states.stateBase] Starting SecurityHealthSafety._sensor_on_command_received
2018-03-18 16:01:20 DEBUG (MainThread) [insteonplm.devices.devicebase] Starting DeviceBase._receive_message
2018-03-18 16:01:20 DEBUG (MainThread) [insteonplm.devices.devicebase] Ending DeviceBase._receive_message
2018-03-18 16:01:21 DEBUG (MainThread) [insteonplm.plm] Starting: data_received
2018-03-18 16:01:21 DEBUG (MainThread) [insteonplm.plm] Received 11 bytes from PLM: b'02502d60ac110104cb0600'
2018-03-18 16:01:21 DEBUG (MainThread) [insteonplm.plm] Total buffer: b'02502d60ac110104cb0600'
2018-03-18 16:01:21 DEBUG (MainThread) [insteonplm.plm] Starting: _peel_messages_from_buffer
2018-03-18 16:01:21 DEBUG (MainThread) [insteonplm.plm] Finishing: _peel_messages_from_buffer
2018-03-18 16:01:21 DEBUG (MainThread) [insteonplm.plm] Messages in queue: 1
2018-03-18 16:01:21 DEBUG (MainThread) [insteonplm.plm] Processing message {'code': 0x50, 'address': 2D.60.AC, 'target': 11.01.04, 'flags': 0xcb, 'cmd1': 0x06, 'cmd2': 0x00}
2018-03-18 16:01:21 DEBUG (MainThread) [insteonplm.plm] Calling method <function DeviceBase._receive_message at 0x734953d8>
2018-03-18 16:01:21 DEBUG (MainThread) [insteonplm.plm] Last item in self._recv_queue reached.
2018-03-18 16:01:21 DEBUG (MainThread) [insteonplm.plm] Finishing: data_received
2018-03-18 16:01:21 DEBUG (MainThread) [insteonplm.devices.devicebase] Starting DeviceBase._receive_message
2018-03-18 16:01:21 DEBUG (MainThread) [insteonplm.devices.devicebase] Ending DeviceBase._receive_message
2018-03-18 16:42:54 DEBUG (MainThread) [insteonplm.plm] Starting: data_received
2018-03-18 16:42:54 DEBUG (MainThread) [insteonplm.plm] Received 22 bytes from PLM: b'02502857be000004cf110402502857be000004cf1104'
2018-03-18 16:42:54 DEBUG (MainThread) [insteonplm.plm] Total buffer: b'02502857be000004cf110402502857be000004cf1104'
2018-03-18 16:42:54 DEBUG (MainThread) [insteonplm.plm] Starting: _peel_messages_from_buffer
2018-03-18 16:42:54 DEBUG (MainThread) [insteonplm.plm] Finishing: _peel_messages_from_buffer
2018-03-18 16:42:54 DEBUG (MainThread) [insteonplm.plm] Messages in queue: 2
2018-03-18 16:42:54 DEBUG (MainThread) [insteonplm.plm] Processing message {'code': 0x50, 'address': 28.57.BE, 'target': 00.00.04, 'flags': 0xcf, 'cmd1': 0x11, 'cmd2': 0x04}
2018-03-18 16:42:54 DEBUG (MainThread) [insteonplm.plm] Calling method <function DeviceBase._receive_message at 0x734953d8>
2018-03-18 16:42:54 DEBUG (MainThread) [insteonplm.plm] Processing message {'code': 0x50, 'address': 28.57.BE, 'target': 00.00.04, 'flags': 0xcf, 'cmd1': 0x11, 'cmd2': 0x04}
2018-03-18 16:42:54 DEBUG (MainThread) [insteonplm.plm] Calling method <function DeviceBase._receive_message at 0x734953d8>
2018-03-18 16:42:54 DEBUG (MainThread) [insteonplm.plm] Last item in self._recv_queue reached.
2018-03-18 16:42:54 DEBUG (MainThread) [insteonplm.plm] Finishing: data_received
2018-03-18 16:42:54 DEBUG (MainThread) [insteonplm.devices.devicebase] Starting DeviceBase._receive_message
2018-03-18 16:42:54 DEBUG (MainThread) [insteonplm.devices.devicebase] Ending DeviceBase._receive_message
2018-03-18 16:42:54 DEBUG (MainThread) [insteonplm.devices.devicebase] Starting DeviceBase._receive_message
2018-03-18 16:42:54 DEBUG (MainThread) [insteonplm.devices.devicebase] Ending DeviceBase._receive_message
2018-03-18 16:42:56 DEBUG (MainThread) [insteonplm.plm] Starting: data_received
2018-03-18 16:42:56 DEBUG (MainThread) [insteonplm.plm] Received 11 bytes from PLM: b'02502857be49f8ee461104'
2018-03-18 16:42:56 DEBUG (MainThread) [insteonplm.plm] Total buffer: b'02502857be49f8ee461104'
2018-03-18 16:42:56 DEBUG (MainThread) [insteonplm.plm] Starting: _peel_messages_from_buffer
2018-03-18 16:42:56 DEBUG (MainThread) [insteonplm.plm] Finishing: _peel_messages_from_buffer
2018-03-18 16:42:56 DEBUG (MainThread) [insteonplm.plm] Messages in queue: 1
2018-03-18 16:42:56 DEBUG (MainThread) [insteonplm.plm] Processing message {'code': 0x50, 'address': 28.57.BE, 'target': 49.F8.EE, 'flags': 0x46, 'cmd1': 0x11, 'cmd2': 0x04}
2018-03-18 16:42:56 DEBUG (MainThread) [insteonplm.plm] Calling method <function DeviceBase._receive_message at 0x734953d8>
2018-03-18 16:42:56 DEBUG (MainThread) [insteonplm.plm] Calling method <function OnOffSensor._sensor_on_command_received at 0x7349ccd8>
2018-03-18 16:42:56 DEBUG (MainThread) [insteonplm.plm] Last item in self._recv_queue reached.
2018-03-18 16:42:56 DEBUG (MainThread) [insteonplm.plm] Finishing: data_received
2018-03-18 16:42:56 DEBUG (MainThread) [insteonplm.devices.devicebase] Starting DeviceBase._receive_message
2018-03-18 16:42:56 DEBUG (MainThread) [insteonplm.devices.devicebase] Ending DeviceBase._receive_message
2018-03-18 16:42:56 DEBUG (MainThread) [insteonplm.states.stateBase] Starting SecurityHealthSafety._sensor_on_command_received
2018-03-18 16:42:56 DEBUG (MainThread) [insteonplm.states.stateBase] Starting SecurityHealthSafety._sensor_on_command_received
2018-03-18 16:42:57 DEBUG (MainThread) [insteonplm.plm] Starting: data_received
2018-03-18 16:42:57 DEBUG (MainThread) [insteonplm.plm] Received 11 bytes from PLM: b'02502857be110104cf0600'
2018-03-18 16:42:57 DEBUG (MainThread) [insteonplm.plm] Total buffer: b'02502857be110104cf0600'
2018-03-18 16:42:57 DEBUG (MainThread) [insteonplm.plm] Starting: _peel_messages_from_buffer
2018-03-18 16:42:57 DEBUG (MainThread) [insteonplm.plm] Finishing: _peel_messages_from_buffer
2018-03-18 16:42:57 DEBUG (MainThread) [insteonplm.plm] Messages in queue: 1
2018-03-18 16:42:57 DEBUG (MainThread) [insteonplm.plm] Processing message {'code': 0x50, 'address': 28.57.BE, 'target': 11.01.04, 'flags': 0xcf, 'cmd1': 0x06, 'cmd2': 0x00}
2018-03-18 16:42:57 DEBUG (MainThread) [insteonplm.plm] Calling method <function DeviceBase._receive_message at 0x734953d8>
2018-03-18 16:42:57 DEBUG (MainThread) [insteonplm.plm] Last item in self._recv_queue reached.
2018-03-18 16:42:57 DEBUG (MainThread) [insteonplm.plm] Finishing: data_received
2018-03-18 16:42:57 DEBUG (MainThread) [insteonplm.devices.devicebase] Starting DeviceBase._receive_message
2018-03-18 16:42:57 DEBUG (MainThread) [insteonplm.devices.devicebase] Ending DeviceBase._receive_message

Madelinot · March 20, 2018, 12:45pm

Still experiencing the same issue. I let it ran a few days, the sensors never returned to their dry states. I rebooted a few times until all sensors and switch responded. There is zero activity during that time (doing this remotely with nobody home).

teharris1 · March 20, 2018, 4:25pm

@Madelinot The issue with the devices not showing up is still unclear to me. I will continue to look at it. As for the sensors indicating a leak, that is a coding issue. It was assumed that the sensor is a simple on/off sensor but that is not the case. I have to rewrite the device to get this correct. Unfortunately that will take a little bit. I will let you know when I have a release that enables the device… Really sorry about that.

Madelinot · March 20, 2018, 4:54pm

No problem… Thanks a lot… Count on me to test once you are ready…

teharris1 · March 20, 2018, 11:53pm

@Madelinot couple of questions:

How long have you owned these leak sensors? Since only three of the five are showing ‘wet’, I am concerned that the other two may have dead batteries or may be outside the range for the signal to be heard. They all should show ‘wet’ based on how this is programmed.
Can you wet one of the sensors and send me the log output? I need to double check the message that the wet state shows.
Ideally, let the sensor then dry out so it sends a ‘dry’ message too but I think I know what that looks like.
Thanks

Madelinot · March 21, 2018, 2:18pm

@teharris1

Some of them are older (4-5 years old), some of them are newer (a few months). I think the batteries are still good, but I could replace them just in case. They are installed in my condo, which is really small and located in another city 300 miles away. There should not be any signal issue, they are all within about 20 feet from the centrally-located PLM.
I will once I go there in about two weeks.
I rebooted a few times until they all show up and they are now all dry. I will let it run for a few days like that and report back.

Thanks!

roborob · March 21, 2018, 3:02pm

Insteon motion sensor logging:

2018-03-17 17:57:48 DEBUG (MainThread) [insteonplm.plm] Received 13 bytes from PLM: b'026a060257e2014168cb101646'
2018-03-17 17:57:48 DEBUG (MainThread) [insteonplm.plm] Total buffer: b'026a060257e2014168cb101646'
2018-03-17 17:57:48 DEBUG (MainThread) [insteonplm.plm] Processing message {'code': 0x57, 'controlFlags': 0xe2, 'group': 0x01, 'address': 41.68.CB, 'linkdata1': 0x10, 'linkdata2': 0x16, 'linkdata3': 0x46}
2018-03-17 17:57:48 INFO (MainThread) [insteonplm.plm] Found all link record for device 4168cb
2018-03-17 17:57:48 DEBUG (MainThread) [insteonplm.plm] Product data: address 4168cb cat: 10 subcat: 16 product_key: 46
2018-03-17 17:57:48 DEBUG (MainThread) [insteonplm.devices.devicebase] Registering DeviceBase._receive_message callbacks for device 41.68.CB
2018-03-17 17:57:48 DEBUG (MainThread) [insteonplm.aldb] New INSTEON Device '4168cb': Generic Security, Heath and Safety Device (10:16)
2018-03-17 17:57:48 INFO (MainThread) [homeassistant.components.insteon_plm] New INSTEON PLM device: 4168cb (generalSensor) sensor
2018-03-17 17:57:48 INFO (MainThread) [insteonplm.plm] Device with id 4168cb added to device list from ALDB data.
2018-03-17 17:57:49 DEBUG (MainThread) [insteonplm.plm] Received 13 bytes from PLM: b'026a060257a2014168cb101646'
2018-03-17 17:57:49 DEBUG (MainThread) [insteonplm.plm] Total buffer: b'026a060257a2014168cb101646'
2018-03-17 17:57:49 DEBUG (MainThread) [insteonplm.plm] Processing message {'code': 0x57, 'controlFlags': 0xa2, 'group': 0x01, 'address': 41.68.CB, 'linkdata1': 0x10, 'linkdata2': 0x16, 'linkdata3': 0x46}
2018-03-17 17:57:50 INFO (MainThread) [insteonplm.plm] Found all link record for device 4168cb
2018-03-17 17:57:53 DEBUG (MainThread) [homeassistant.components.sensor.insteon_plm] Adding device 4168cb entity generalSensor to Sensor platform
2018-03-17 17:57:58 DEBUG (MainThread) [insteonplm.plm] Received 13 bytes from PLM: b'026a060257a20145faa8023843'
2018-03-17 17:57:58 DEBUG (MainThread) [insteonplm.plm] Total buffer: b'026a060257a20145faa8023843'
2018-03-17 17:57:58 DEBUG (MainThread) [insteonplm.plm] Processing message {'code': 0x57, 'controlFlags': 0xa2, 'group': 0x01, 'address': 45.FA.A8, 'linkdata1': 0x02, 'linkdata2': 0x38, 'linkdata3': 0x43}
2018-03-17 17:57:58 INFO (MainThread) [insteonplm.plm] Found all link record for device 45faa8
2018-03-17 17:57:58 DEBUG (MainThread) [insteonplm.plm] Product data: address 45faa8 cat: 02 subcat: 38 product_key: 43
2018-03-17 17:57:58 DEBUG (MainThread) [insteonplm.devices.devicebase] Registering DeviceBase._receive_message callbacks for device 45.FA.A8
2018-03-17 17:57:58 DEBUG (MainThread) [insteonplm.aldb] New INSTEON Device '45faa8': Generic Security, Heath and Safety Device (10:16)
2018-03-17 17:57:58 INFO (MainThread) [homeassistant.components.insteon_plm] New INSTEON PLM device: 45faa8 (generalSensor) sensor
2018-03-17 17:57:58 INFO (MainThread) [insteonplm.plm] Device with id 45faa8 added to device list from ALDB data.
2018-03-17 17:58:00 DEBUG (MainThread) [homeassistant.components.sensor.insteon_plm] Adding device 45faa8 entity generalSensor to Sensor platform
2018-03-17 17:58:01 DEBUG (MainThread) [insteonplm.plm] Received 13 bytes from PLM: b'026a060257e20145faa8101646'
2018-03-17 17:58:01 DEBUG (MainThread) [insteonplm.plm] Total buffer: b'026a060257e20145faa8101646'
2018-03-17 17:58:01 DEBUG (MainThread) [insteonplm.plm] Processing message {'code': 0x57, 'controlFlags': 0xe2, 'group': 0x01, 'address': 45.FA.A8, 'linkdata1': 0x10, 'linkdata2': 0x16, 'linkdata3': 0x46}
2018-03-17 17:58:01 INFO (MainThread) [insteonplm.plm] Found all link record for device 45faa8
2018-03-17 21:30:30 DEBUG (MainThread) [insteonplm.plm] Received 44 bytes from PLM: b'025045faa8000001cf1101025045faa8000001cf1101025045faa844851a401101025045faa8110101cf0600'
2018-03-17 21:30:30 DEBUG (MainThread) [insteonplm.plm] Total buffer: b'025045faa8000001cf1101025045faa8000001cf1101025045faa844851a401101025045faa8110101cf0600'
2018-03-17 21:30:30 DEBUG (MainThread) [insteonplm.plm] Processing message {'code': 0x50, 'address': 45.FA.A8, 'target': 00.00.01, 'flags': 0xcf, 'cmd1': 0x11, 'cmd2': 0x01}
2018-03-17 21:30:30 DEBUG (MainThread) [insteonplm.plm] Processing message {'code': 0x50, 'address': 45.FA.A8, 'target': 00.00.01, 'flags': 0xcf, 'cmd1': 0x11, 'cmd2': 0x01}
2018-03-17 21:30:30 DEBUG (MainThread) [insteonplm.plm] Processing message {'code': 0x50, 'address': 45.FA.A8, 'target': 44.85.1A, 'flags': 0x40, 'cmd1': 0x11, 'cmd2': 0x01}
2018-03-17 21:30:30 DEBUG (MainThread) [insteonplm.plm] Processing message {'code': 0x50, 'address': 45.FA.A8, 'target': 11.01.01, 'flags': 0xcf, 'cmd1': 0x06, 'cmd2': 0x00}
2018-03-17 21:30:31 DEBUG (MainThread) [insteonplm.plm] Received 11 bytes from PLM: b'025045faa8110101cf0600'
2018-03-17 21:30:31 DEBUG (MainThread) [insteonplm.plm] Total buffer: b'025045faa8110101cf0600'
2018-03-17 21:30:31 DEBUG (MainThread) [insteonplm.plm] Processing message {'code': 0x50, 'address': 45.FA.A8, 'target': 11.01.01, 'flags': 0xcf, 'cmd1': 0x06, 'cmd2': 0x00}
2018-03-17 21:31:41 DEBUG (MainThread) [insteonplm.plm] Received 44 bytes from PLM: b'025045faa8000001cf1301025045faa8000001cf1301025045faa844851a401301025045faa8130101cf0600'
2018-03-17 21:31:41 DEBUG (MainThread) [insteonplm.plm] Total buffer: b'025045faa8000001cf1301025045faa8000001cf1301025045faa844851a401301025045faa8130101cf0600'
2018-03-17 21:31:41 DEBUG (MainThread) [insteonplm.plm] Processing message {'code': 0x50, 'address': 45.FA.A8, 'target': 00.00.01, 'flags': 0xcf, 'cmd1': 0x13, 'cmd2': 0x01}
2018-03-17 21:31:41 DEBUG (MainThread) [insteonplm.plm] Processing message {'code': 0x50, 'address': 45.FA.A8, 'target': 00.00.01, 'flags': 0xcf, 'cmd1': 0x13, 'cmd2': 0x01}
2018-03-17 21:31:41 DEBUG (MainThread) [insteonplm.plm] Processing message {'code': 0x50, 'address': 45.FA.A8, 'target': 44.85.1A, 'flags': 0x40, 'cmd1': 0x13, 'cmd2': 0x01}
2018-03-17 21:31:41 DEBUG (MainThread) [insteonplm.plm] Processing message {'code': 0x50, 'address': 45.FA.A8, 'target': 13.01.01, 'flags': 0xcf, 'cmd1': 0x06, 'cmd2': 0x00}
2018-03-17 21:31:42 DEBUG (MainThread) [insteonplm.plm] Received 11 bytes from PLM: b'025045faa8130101cf0600'
2018-03-17 21:31:42 DEBUG (MainThread) [insteonplm.plm] Total buffer: b'025045faa8130101cf0600'
2018-03-17 21:31:42 DEBUG (MainThread) [insteonplm.plm] Processing message {'code': 0x50, 'address': 45.FA.A8, 'target': 13.01.01, 'flags': 0xcf, 'cmd1': 0x06, 'cmd2': 0x00}
2018-03-17 21:32:34 DEBUG (MainThread) [insteonplm.plm] Received 44 bytes from PLM: b'025045faa8000001cf1101025045faa8000001cf1101025045faa844851a401101025045faa8110101cf0600'
2018-03-17 21:32:34 DEBUG (MainThread) [insteonplm.plm] Total buffer: b'025045faa8000001cf1101025045faa8000001cf1101025045faa844851a401101025045faa8110101cf0600'
2018-03-17 21:32:34 DEBUG (MainThread) [insteonplm.plm] Processing message {'code': 0x50, 'address': 45.FA.A8, 'target': 00.00.01, 'flags': 0xcf, 'cmd1': 0x11, 'cmd2': 0x01}
2018-03-17 21:32:34 DEBUG (MainThread) [insteonplm.plm] Processing message {'code': 0x50, 'address': 45.FA.A8, 'target': 00.00.01, 'flags': 0xcf, 'cmd1': 0x11, 'cmd2': 0x01}
2018-03-17 21:32:34 DEBUG (MainThread) [insteonplm.plm] Processing message {'code': 0x50, 'address': 45.FA.A8, 'target': 44.85.1A, 'flags': 0x40, 'cmd1': 0x11, 'cmd2': 0x01}
2018-03-17 21:32:34 DEBUG (MainThread) [insteonplm.plm] Processing message {'code': 0x50, 'address': 45.FA.A8, 'target': 11.01.01, 'flags': 0xcf, 'cmd1': 0x06, 'cmd2': 0x00}
2018-03-17 21:32:35 DEBUG (MainThread) [insteonplm.plm] Received 11 bytes from PLM: b'025045faa8110101cf0600'
2018-03-17 21:32:35 DEBUG (MainThread) [insteonplm.plm] Total buffer: b'025045faa8110101cf0600'
2018-03-17 21:32:35 DEBUG (MainThread) [insteonplm.plm] Processing message {'code': 0x50, 'address': 45.FA.A8, 'target': 11.01.01, 'flags': 0xcf, 'cmd1': 0x06, 'cmd2': 0x00}
2018-03-17 21:33:08 DEBUG (MainThread) [insteonplm.plm] Received 44 bytes from PLM: b'025045faa8000001cf1301025045faa8000001cf1301025045faa844851a401301025045faa8130101cf0600'
2018-03-17 21:33:08 DEBUG (MainThread) [insteonplm.plm] Total buffer: b'025045faa8000001cf1301025045faa8000001cf1301025045faa844851a401301025045faa8130101cf0600'
2018-03-17 21:33:08 DEBUG (MainThread) [insteonplm.plm] Processing message {'code': 0x50, 'address': 45.FA.A8, 'target': 00.00.01, 'flags': 0xcf, 'cmd1': 0x13, 'cmd2': 0x01}
2018-03-17 21:33:08 DEBUG (MainThread) [insteonplm.plm] Processing message {'code': 0x50, 'address': 45.FA.A8, 'target': 00.00.01, 'flags': 0xcf, 'cmd1': 0x13, 'cmd2': 0x01}
2018-03-17 21:33:09 DEBUG (MainThread) [insteonplm.plm] Processing message {'code': 0x50, 'address': 45.FA.A8, 'target': 44.85.1A, 'flags': 0x40, 'cmd1': 0x13, 'cmd2': 0x01}
2018-03-17 21:33:09 DEBUG (MainThread) [insteonplm.plm] Processing message {'code': 0x50, 'address': 45.FA.A8, 'target': 13.01.01, 'flags': 0xcf, 'cmd1': 0x06, 'cmd2': 0x00}
2018-03-17 21:33:10 DEBUG (MainThread) [insteonplm.plm] Received 11 bytes from PLM: b'025045faa8130101cf0600'
2018-03-17 21:33:10 DEBUG (MainThread) [insteonplm.plm] Total buffer: b'025045faa8130101cf0600'
2018-03-17 21:33:10 DEBUG (MainThread) [insteonplm.plm] Processing message {'code': 0x50, 'address': 45.FA.A8, 'target': 13.01.01, 'flags': 0xcf, 'cmd1': 0x06, 'cmd2': 0x00}
2018-03-18 04:20:35 DEBUG (MainThread) [insteonplm.plm] Received 44 bytes from PLM: b'02504168cb000001cf110102504168cb000001cf110102504168cb44851a40110102504168cb110101cf0600'
2018-03-18 04:20:35 DEBUG (MainThread) [insteonplm.plm] Total buffer: b'02504168cb000001cf110102504168cb000001cf110102504168cb44851a40110102504168cb110101cf0600'
2018-03-18 04:20:35 DEBUG (MainThread) [insteonplm.plm] Processing message {'code': 0x50, 'address': 41.68.CB, 'target': 00.00.01, 'flags': 0xcf, 'cmd1': 0x11, 'cmd2': 0x01}
2018-03-18 04:20:35 DEBUG (MainThread) [insteonplm.plm] Processing message {'code': 0x50, 'address': 41.68.CB, 'target': 00.00.01, 'flags': 0xcf, 'cmd1': 0x11, 'cmd2': 0x01}
2018-03-18 04:20:35 DEBUG (MainThread) [insteonplm.plm] Processing message {'code': 0x50, 'address': 41.68.CB, 'target': 44.85.1A, 'flags': 0x40, 'cmd1': 0x11, 'cmd2': 0x01}
2018-03-18 04:20:35 DEBUG (MainThread) [insteonplm.plm] Processing message {'code': 0x50, 'address': 41.68.CB, 'target': 11.01.01, 'flags': 0xcf, 'cmd1': 0x06, 'cmd2': 0x00}
2018-03-18 04:20:37 DEBUG (MainThread) [insteonplm.plm] Received 11 bytes from PLM: b'02504168cb110101cf0600'
2018-03-18 04:20:37 DEBUG (MainThread) [insteonplm.plm] Total buffer: b'02504168cb110101cf0600'
2018-03-18 04:20:37 DEBUG (MainThread) [insteonplm.plm] Processing message {'code': 0x50, 'address': 41.68.CB, 'target': 11.01.01, 'flags': 0xcf, 'cmd1': 0x06, 'cmd2': 0x00}
2018-03-18 04:21:17 DEBUG (MainThread) [insteonplm.plm] Received 44 bytes from PLM: b'02504168cb000001cf130102504168cb000001cf130102504168cb44851a40130102504168cb130101cf0600'
2018-03-18 04:21:17 DEBUG (MainThread) [insteonplm.plm] Total buffer: b'02504168cb000001cf130102504168cb000001cf130102504168cb44851a40130102504168cb130101cf0600'
2018-03-18 04:21:17 DEBUG (MainThread) [insteonplm.plm] Processing message {'code': 0x50, 'address': 41.68.CB, 'target': 00.00.01, 'flags': 0xcf, 'cmd1': 0x13, 'cmd2': 0x01}
2018-03-18 04:21:17 DEBUG (MainThread) [insteonplm.plm] Processing message {'code': 0x50, 'address': 41.68.CB, 'target': 00.00.01, 'flags': 0xcf, 'cmd1': 0x13, 'cmd2': 0x01}
2018-03-18 04:21:17 DEBUG (MainThread) [insteonplm.plm] Processing message {'code': 0x50, 'address': 41.68.CB, 'target': 44.85.1A, 'flags': 0x40, 'cmd1': 0x13, 'cmd2': 0x01}
2018-03-18 04:21:17 DEBUG (MainThread) [insteonplm.plm] Processing message {'code': 0x50, 'address': 41.68.CB, 'target': 13.01.01, 'flags': 0xcf, 'cmd1': 0x06, 'cmd2': 0x00}
2018-03-18 04:21:18 DEBUG (MainThread) [insteonplm.plm] Received 11 bytes from PLM: b'02504168cb130101cf0600'
2018-03-18 04:21:18 DEBUG (MainThread) [insteonplm.plm] Total buffer: b'02504168cb130101cf0600'
2018-03-18 04:21:18 DEBUG (MainThread) [insteonplm.plm] Processing message {'code': 0x50, 'address': 41.68.CB, 'target': 13.01.01, 'flags': 0xcf, 'cmd1': 0x06, 'cmd2': 0x00}
2018-03-18 07:11:03 DEBUG (MainThread) [insteonplm.plm] Received 44 bytes from PLM: b'025045faa8000001cf1101025045faa8000001cf1101025045faa844851a401101025045faa8110101cf0600'
2018-03-18 07:11:03 DEBUG (MainThread) [insteonplm.plm] Total buffer: b'025045faa8000001cf1101025045faa8000001cf1101025045faa844851a401101025045faa8110101cf0600'
2018-03-18 07:11:03 DEBUG (MainThread) [insteonplm.plm] Processing message {'code': 0x50, 'address': 45.FA.A8, 'target': 00.00.01, 'flags': 0xcf, 'cmd1': 0x11, 'cmd2': 0x01}
2018-03-18 07:11:03 DEBUG (MainThread) [insteonplm.plm] Processing message {'code': 0x50, 'address': 45.FA.A8, 'target': 00.00.01, 'flags': 0xcf, 'cmd1': 0x11, 'cmd2': 0x01}
2018-03-18 07:11:03 DEBUG (MainThread) [insteonplm.plm] Processing message {'code': 0x50, 'address': 45.FA.A8, 'target': 44.85.1A, 'flags': 0x40, 'cmd1': 0x11, 'cmd2': 0x01}
2018-03-18 07:11:03 DEBUG (MainThread) [insteonplm.plm] Processing message {'code': 0x50, 'address': 45.FA.A8, 'target': 11.01.01, 'flags': 0xcf, 'cmd1': 0x06, 'cmd2': 0x00}
2018-03-18 07:11:04 DEBUG (MainThread) [insteonplm.plm] Received 11 bytes from PLM: b'025045faa8110101cf0600'
2018-03-18 07:11:04 DEBUG (MainThread) [insteonplm.plm] Total buffer: b'025045faa8110101cf0600'
2018-03-18 07:11:04 DEBUG (MainThread) [insteonplm.plm] Processing message {'code': 0x50, 'address': 45.FA.A8, 'target': 11.01.01, 'flags': 0xcf, 'cmd1': 0x06, 'cmd2': 0x00}
2018-03-18 07:11:37 DEBUG (MainThread) [insteonplm.plm] Received 44 bytes from PLM: b'025045faa8000001cf1301025045faa8000001cf1301025045faa844851a401301025045faa8130101cf0600'
2018-03-18 07:11:37 DEBUG (MainThread) [insteonplm.plm] Total buffer: b'025045faa8000001cf1301025045faa8000001cf1301025045faa844851a401301025045faa8130101cf0600'
2018-03-18 07:11:37 DEBUG (MainThread) [insteonplm.plm] Processing message {'code': 0x50, 'address': 45.FA.A8, 'target': 00.00.01, 'flags': 0xcf, 'cmd1': 0x13, 'cmd2': 0x01}
2018-03-18 07:11:37 DEBUG (MainThread) [insteonplm.plm] Processing message {'code': 0x50, 'address': 45.FA.A8, 'target': 00.00.01, 'flags': 0xcf, 'cmd1': 0x13, 'cmd2': 0x01}
2018-03-18 07:11:37 DEBUG (MainThread) [insteonplm.plm] Processing message {'code': 0x50, 'address': 45.FA.A8, 'target': 44.85.1A, 'flags': 0x40, 'cmd1': 0x13, 'cmd2': 0x01}
2018-03-18 07:11:37 DEBUG (MainThread) [insteonplm.plm] Processing message {'code': 0x50, 'address': 45.FA.A8, 'target': 13.01.01, 'flags': 0xcf, 'cmd1': 0x06, 'cmd2': 0x00}
2018-03-18 07:11:38 DEBUG (MainThread) [insteonplm.plm] Received 11 bytes from PLM: b'025045faa8130101cf0600'
2018-03-18 07:11:38 DEBUG (MainThread) [insteonplm.plm] Total buffer: b'025045faa8130101cf0600'
2018-03-18 07:11:38 DEBUG (MainThread) [insteonplm.plm] Processing message {'code': 0x50, 'address': 45.FA.A8, 'target': 13.01.01, 'flags': 0xcf, 'cmd1': 0x06, 'cmd2': 0x00}
2018-03-18 07:51:07 DEBUG (MainThread) [insteonplm.plm] Received 44 bytes from PLM: b'025045faa8000001cf1101025045faa8000001cf1101025045faa844851a401101025045faa8110101cf0600'
2018-03-18 07:51:07 DEBUG (MainThread) [insteonplm.plm] Total buffer: b'025045faa8000001cf1101025045faa8000001cf1101025045faa844851a401101025045faa8110101cf0600'
2018-03-18 07:51:07 DEBUG (MainThread) [insteonplm.plm] Processing message {'code': 0x50, 'address': 45.FA.A8, 'target': 00.00.01, 'flags': 0xcf, 'cmd1': 0x11, 'cmd2': 0x01}
2018-03-18 07:51:07 DEBUG (MainThread) [insteonplm.plm] Processing message {'code': 0x50, 'address': 45.FA.A8, 'target': 00.00.01, 'flags': 0xcf, 'cmd1': 0x11, 'cmd2': 0x01}
2018-03-18 07:51:07 DEBUG (MainThread) [insteonplm.plm] Processing message {'code': 0x50, 'address': 45.FA.A8, 'target': 44.85.1A, 'flags': 0x40, 'cmd1': 0x11, 'cmd2': 0x01}
2018-03-18 07:51:07 DEBUG (MainThread) [insteonplm.plm] Processing message {'code': 0x50, 'address': 45.FA.A8, 'target': 11.01.01, 'flags': 0xcf, 'cmd1': 0x06, 'cmd2': 0x00}
2018-03-18 07:51:08 DEBUG (MainThread) [insteonplm.plm] Received 11 bytes from PLM: b'025045faa8110101cf0600'
2018-03-18 07:51:08 DEBUG (MainThread) [insteonplm.plm] Total buffer: b'025045faa8110101cf0600'
2018-03-18 07:51:08 DEBUG (MainThread) [insteonplm.plm] Processing message {'code': 0x50, 'address': 45.FA.A8, 'target': 11.01.01, 'flags': 0xcf, 'cmd1': 0x06, 'cmd2': 0x00}
2018-03-18 07:51:41 DEBUG (MainThread) [insteonplm.plm] Received 44 bytes from PLM: b'025045faa8000001cf1301025045faa8000001cf1301025045faa844851a401301025045faa8130101cf0600'
2018-03-18 07:51:41 DEBUG (MainThread) [insteonplm.plm] Total buffer: b'025045faa8000001cf1301025045faa8000001cf1301025045faa844851a401301025045faa8130101cf0600'
2018-03-18 07:51:41 DEBUG (MainThread) [insteonplm.plm] Processing message {'code': 0x50, 'address': 45.FA.A8, 'target': 00.00.01, 'flags': 0xcf, 'cmd1': 0x13, 'cmd2': 0x01}
2018-03-18 07:51:41 DEBUG (MainThread) [insteonplm.plm] Processing message {'code': 0x50, 'address': 45.FA.A8, 'target': 00.00.01, 'flags': 0xcf, 'cmd1': 0x13, 'cmd2': 0x01}
2018-03-18 07:51:41 DEBUG (MainThread) [insteonplm.plm] Processing message {'code': 0x50, 'address': 45.FA.A8, 'target': 44.85.1A, 'flags': 0x40, 'cmd1': 0x13, 'cmd2': 0x01}
2018-03-18 07:51:41 DEBUG (MainThread) [insteonplm.plm] Processing message {'code': 0x50, 'address': 45.FA.A8, 'target': 13.01.01, 'flags': 0xcf, 'cmd1': 0x06, 'cmd2': 0x00}
2018-03-18 07:51:42 DEBUG (MainThread) [insteonplm.plm] Received 11 bytes from PLM: b'025045faa8130101cf0600'
2018-03-18 07:51:42 DEBUG (MainThread) [insteonplm.plm] Total buffer: b'025045faa8130101cf0600'
2018-03-18 07:51:42 DEBUG (MainThread) [insteonplm.plm] Processing message {'code': 0x50, 'address': 45.FA.A8, 'target': 13.01.01, 'flags': 0xcf, 'cmd1': 0x06, 'cmd2': 0x00}
2018-03-18 08:21:31 DEBUG (MainThread) [insteonplm.plm] Received 44 bytes from PLM: b'02504168cb000001cf110102504168cb000001cf110102504168cb44851a40110102504168cb110101cf0600'
2018-03-18 08:21:31 DEBUG (MainThread) [insteonplm.plm] Total buffer: b'02504168cb000001cf110102504168cb000001cf110102504168cb44851a40110102504168cb110101cf0600'
2018-03-18 08:21:31 DEBUG (MainThread) [insteonplm.plm] Processing message {'code': 0x50, 'address': 41.68.CB, 'target': 00.00.01, 'flags': 0xcf, 'cmd1': 0x11, 'cmd2': 0x01}
2018-03-18 08:21:31 DEBUG (MainThread) [insteonplm.plm] Processing message {'code': 0x50, 'address': 41.68.CB, 'target': 00.00.01, 'flags': 0xcf, 'cmd1': 0x11, 'cmd2': 0x01}
2018-03-18 08:21:31 DEBUG (MainThread) [insteonplm.plm] Processing message {'code': 0x50, 'address': 41.68.CB, 'target': 44.85.1A, 'flags': 0x40, 'cmd1': 0x11, 'cmd2': 0x01}
2018-03-18 08:21:31 DEBUG (MainThread) [insteonplm.plm] Processing message {'code': 0x50, 'address': 41.68.CB, 'target': 11.01.01, 'flags': 0xcf, 'cmd1': 0x06, 'cmd2': 0x00}
2018-03-18 08:21:32 DEBUG (MainThread) [insteonplm.plm] Received 11 bytes from PLM: b'02504168cb110101cf0600'
2018-03-18 08:21:32 DEBUG (MainThread) [insteonplm.plm] Total buffer: b'02504168cb110101cf0600'
2018-03-18 08:21:32 DEBUG (MainThread) [insteonplm.plm] Processing message {'code': 0x50, 'address': 41.68.CB, 'target': 11.01.01, 'flags': 0xcf, 'cmd1': 0x06, 'cmd2': 0x00}
2018-03-18 08:22:11 DEBUG (MainThread) [insteonplm.plm] Received 44 bytes from PLM: b'02504168cb000001cf130102504168cb000001cf130102504168cb44851a40130102504168cb130101cf0600'
2018-03-18 08:22:11 DEBUG (MainThread) [insteonplm.plm] Total buffer: b'02504168cb000001cf130102504168cb000001cf130102504168cb44851a40130102504168cb130101cf0600'
2018-03-18 08:22:11 DEBUG (MainThread) [insteonplm.plm] Processing message {'code': 0x50, 'address': 41.68.CB, 'target': 00.00.01, 'flags': 0xcf, 'cmd1': 0x13, 'cmd2': 0x01}
2018-03-18 08:22:11 DEBUG (MainThread) [insteonplm.plm] Processing message {'code': 0x50, 'address': 41.68.CB, 'target': 00.00.01, 'flags': 0xcf, 'cmd1': 0x13, 'cmd2': 0x01}
2018-03-18 08:22:11 DEBUG (MainThread) [insteonplm.plm] Processing message {'code': 0x50, 'address': 41.68.CB, 'target': 44.85.1A, 'flags': 0x40, 'cmd1': 0x13, 'cmd2': 0x01}
2018-03-18 08:22:11 DEBUG (MainThread) [insteonplm.plm] Processing message {'code': 0x50, 'address': 41.68.CB, 'target': 13.01.01, 'flags': 0xcf, 'cmd1': 0x06, 'cmd2': 0x00}
2018-03-18 08:22:12 DEBUG (MainThread) [insteonplm.plm] Received 11 bytes from PLM: b'02504168cb130101cf0600'
2018-03-18 08:22:12 DEBUG (MainThread) [insteonplm.plm] Total buffer: b'02504168cb130101cf0600'
2018-03-18 08:22:12 DEBUG (MainThread) [insteonplm.plm] Processing message {'code': 0x50, 'address': 41.68.CB, 'target': 13.01.01, 'flags': 0xcf, 'cmd1': 0x06, 'cmd2': 0x00}
2018-03-18 09:07:21 DEBUG (MainThread) [insteonplm.plm] Received 44 bytes from PLM: b'02504168cb000001cf110102504168cb000001cf110102504168cb44851a40110102504168cb110101cf0600'
2018-03-18 09:07:21 DEBUG (MainThread) [insteonplm.plm] Total buffer: b'02504168cb000001cf110102504168cb000001cf110102504168cb44851a40110102504168cb110101cf0600'
2018-03-18 09:07:21 DEBUG (MainThread) [insteonplm.plm] Processing message {'code': 0x50, 'address': 41.68.CB, 'target': 00.00.01, 'flags': 0xcf, 'cmd1': 0x11, 'cmd2': 0x01}
2018-03-18 09:07:21 DEBUG (MainThread) [insteonplm.plm] Processing message {'code': 0x50, 'address': 41.68.CB, 'target': 00.00.01, 'flags': 0xcf, 'cmd1': 0x11, 'cmd2': 0x01}
2018-03-18 09:07:21 DEBUG (MainThread) [insteonplm.plm] Processing message {'code': 0x50, 'address': 41.68.CB, 'target': 44.85.1A, 'flags': 0x40, 'cmd1': 0x11, 'cmd2': 0x01}
2018-03-18 09:07:21 DEBUG (MainThread) [insteonplm.plm] Processing message {'code': 0x50, 'address': 41.68.CB, 'target': 11.01.01, 'flags': 0xcf, 'cmd1': 0x06, 'cmd2': 0x00}
2018-03-18 09:07:22 DEBUG (MainThread) [insteonplm.plm] Received 11 bytes from PLM: b'02504168cb110101cf0600'
2018-03-18 09:07:22 DEBUG (MainThread) [insteonplm.plm] Total buffer: b'02504168cb110101cf0600'
2018-03-18 09:07:22 DEBUG (MainThread) [insteonplm.plm] Processing message {'code': 0x50, 'address': 41.68.CB, 'target': 11.01.01, 'flags': 0xcf, 'cmd1': 0x06, 'cmd2': 0x00}
2018-03-18 09:07:57 DEBUG (MainThread) [insteonplm.plm] Received 44 bytes from PLM: b'02504168cb000001cf130102504168cb000001cf130102504168cb44851a40130102504168cb130101cf0600'
2018-03-18 09:07:57 DEBUG (MainThread) [insteonplm.plm] Total buffer: b'02504168cb000001cf130102504168cb000001cf130102504168cb44851a40130102504168cb130101cf0600'
2018-03-18 09:07:57 DEBUG (MainThread) [insteonplm.plm] Processing message {'code': 0x50, 'address': 41.68.CB, 'target': 00.00.01, 'flags': 0xcf, 'cmd1': 0x13, 'cmd2': 0x01}
2018-03-18 09:07:58 DEBUG (MainThread) [insteonplm.plm] Processing message {'code': 0x50, 'address': 41.68.CB, 'target': 00.00.01, 'flags': 0xcf, 'cmd1': 0x13, 'cmd2': 0x01}
2018-03-18 09:07:58 DEBUG (MainThread) [insteonplm.plm] Processing message {'code': 0x50, 'address': 41.68.CB, 'target': 44.85.1A, 'flags': 0x40, 'cmd1': 0x13, 'cmd2': 0x01}
2018-03-18 09:07:58 DEBUG (MainThread) [insteonplm.plm] Processing message {'code': 0x50, 'address': 41.68.CB, 'target': 13.01.01, 'flags': 0xcf, 'cmd1': 0x06, 'cmd2': 0x00}
2018-03-18 09:07:59 DEBUG (MainThread) [insteonplm.plm] Received 11 bytes from PLM: b'02504168cb130101cf0600'
2018-03-18 09:07:59 DEBUG (MainThread) [insteonplm.plm] Total buffer: b'02504168cb130101cf0600'
2018-03-18 09:07:59 DEBUG (MainThread) [insteonplm.plm] Processing message {'code': 0x50, 'address': 41.68.CB, 'target': 13.01.01, 'flags': 0xcf, 'cmd1': 0x06, 'cmd2': 0x00}
2018-03-18 09:58:24 DEBUG (MainThread) [insteonplm.plm] Received 44 bytes from PLM: b'02504168cb000001cf110102504168cb000001cf110102504168cb44851a40110102504168cb110101cf0600'
2018-03-18 09:58:24 DEBUG (MainThread) [insteonplm.plm] Total buffer: b'02504168cb000001cf110102504168cb000001cf110102504168cb44851a40110102504168cb110101cf0600'
2018-03-18 09:58:24 DEBUG (MainThread) [insteonplm.plm] Processing message {'code': 0x50, 'address': 41.68.CB, 'target': 00.00.01, 'flags': 0xcf, 'cmd1': 0x11, 'cmd2': 0x01}
2018-03-18 09:58:24 DEBUG (MainThread) [insteonplm.plm] Processing message {'code': 0x50, 'address': 41.68.CB, 'target': 00.00.01, 'flags': 0xcf, 'cmd1': 0x11, 'cmd2': 0x01}
2018-03-18 09:58:24 DEBUG (MainThread) [insteonplm.plm] Processing message {'code': 0x50, 'address': 41.68.CB, 'target': 44.85.1A, 'flags': 0x40, 'cmd1': 0x11, 'cmd2': 0x01}
2018-03-18 09:58:24 DEBUG (MainThread) [insteonplm.plm] Processing message {'code': 0x50, 'address': 41.68.CB, 'target': 11.01.01, 'flags': 0xcf, 'cmd1': 0x06, 'cmd2': 0x00}
2018-03-18 09:58:26 DEBUG (MainThread) [insteonplm.plm] Received 11 bytes from PLM: b'02504168cb110101cf0600'
2018-03-18 09:58:26 DEBUG (MainThread) [insteonplm.plm] Total buffer: b'02504168cb110101cf0600'
2018-03-18 09:58:26 DEBUG (MainThread) [insteonplm.plm] Processing message {'code': 0x50, 'address': 41.68.CB, 'target': 11.01.01, 'flags': 0xcf, 'cmd1': 0x06, 'cmd2': 0x00}
2018-03-18 09:59:06 DEBUG (MainThread) [insteonplm.plm] Received 44 bytes from PLM: b'02504168cb000001cf130102504168cb000001cf130102504168cb44851a40130102504168cb130101cf0600'
2018-03-18 09:59:06 DEBUG (MainThread) [insteonplm.plm] Total buffer: b'02504168cb000001cf130102504168cb000001cf130102504168cb44851a40130102504168cb130101cf0600'
2018-03-18 09:59:06 DEBUG (MainThread) [insteonplm.plm] Processing message {'code': 0x50, 'address': 41.68.CB, 'target': 00.00.01, 'flags': 0xcf, 'cmd1': 0x13, 'cmd2': 0x01}
2018-03-18 09:59:06 DEBUG (MainThread) [insteonplm.plm] Processing message {'code': 0x50, 'address': 41.68.CB, 'target': 00.00.01, 'flags': 0xcf, 'cmd1': 0x13, 'cmd2': 0x01}
2018-03-18 09:59:06 DEBUG (MainThread) [insteonplm.plm] Processing message {'code': 0x50, 'address': 41.68.CB, 'target': 44.85.1A, 'flags': 0x40, 'cmd1': 0x13, 'cmd2': 0x01}
2018-03-18 09:59:06 DEBUG (MainThread) [insteonplm.plm] Processing message {'code': 0x50, 'address': 41.68.CB, 'target': 13.01.01, 'flags': 0xcf, 'cmd1': 0x06, 'cmd2': 0x00}
2018-03-18 09:59:07 DEBUG (MainThread) [insteonplm.plm] Received 11 bytes from PLM: b'02504168cb130101cf0600'
2018-03-18 09:59:07 DEBUG (MainThread) [insteonplm.plm] Total buffer: b'02504168cb130101cf0600'
2018-03-18 09:59:07 DEBUG (MainThread) [insteonplm.plm] Processing message {'code': 0x50, 'address': 41.68.CB, 'target': 13.01.01, 'flags': 0xcf, 'cmd1': 0x06, 'cmd2': 0x00}
2018-03-18 10:04:14 DEBUG (MainThread) [insteonplm.plm] Received 44 bytes from PLM: b'02504168cb000001cf110102504168cb000001cf110102504168cb44851a40110102504168cb110101cf0600'
2018-03-18 10:04:14 DEBUG (MainThread) [insteonplm.plm] Total buffer: b'02504168cb000001cf110102504168cb000001cf110102504168cb44851a40110102504168cb110101cf0600'
2018-03-18 10:04:14 DEBUG (MainThread) [insteonplm.plm] Processing message {'code': 0x50, 'address': 41.68.CB, 'target': 00.00.01, 'flags': 0xcf, 'cmd1': 0x11, 'cmd2': 0x01}
2018-03-18 10:04:14 DEBUG (MainThread) [insteonplm.plm] Processing message {'code': 0x50, 'address': 41.68.CB, 'target': 00.00.01, 'flags': 0xcf, 'cmd1': 0x11, 'cmd2': 0x01}
2018-03-18 10:04:14 DEBUG (MainThread) [insteonplm.plm] Processing message {'code': 0x50, 'address': 41.68.CB, 'target': 44.85.1A, 'flags': 0x40, 'cmd1': 0x11, 'cmd2': 0x01}
2018-03-18 10:04:14 DEBUG (MainThread) [insteonplm.plm] Processing message {'code': 0x50, 'address': 41.68.CB, 'target': 11.01.01, 'flags': 0xcf, 'cmd1': 0x06, 'cmd2': 0x00}
2018-03-18 10:04:16 DEBUG (MainThread) [insteonplm.plm] Received 11 bytes from PLM: b'02504168cb110101cf0600'
2018-03-18 10:04:16 DEBUG (MainThread) [insteonplm.plm] Total buffer: b'02504168cb110101cf0600'
2018-03-18 10:04:16 DEBUG (MainThread) [insteonplm.plm] Processing message {'code': 0x50, 'address': 41.68.CB, 'target': 11.01.01, 'flags': 0xcf, 'cmd1': 0x06, 'cmd2': 0x00}

Edit:
OK, I’m not able to post additional replies in this thread, so that’s it for now, but perhaps there’s plenty there already to debug the new motion sensors.

(PS, I love the message “Are you sure you want to abandon your post?” I felt like I was going AWOL )

teharris1 · March 23, 2018, 1:27am

@Madelinot I found some time to recode the leak sensors. They are on my git repo. Here are the files you need to download and replace:
Module insteonplm:
insteonplm/devices/securityHealthSafety.py
insteonplm/states/sensor.py

Module homeassistant:
homeassistant/components/insteon_plm.py
homeassistant/components/binary_sensor/insteon_plm.py
Please replace the current files with these files and restart HA. I have tested the code as best I can but I don’t own one of these leak sensors so I cannot test if it works.

teharris1 · March 23, 2018, 1:38am

@roborob The data you sent is plenty good enough. Thanks. This is exactly like the older model so for now using device overrides should work. If it does not please open another thread and we will address it there. The change is pretty easy so it will be in the next release.

Madelinot · March 23, 2018, 1:50am

Humm… Thanks a lot. that’s great, but I can’t really test right now because I’m running HASSio…

Madelinot · March 24, 2018, 2:05pm

Running HASSio, if I copy those files with the folders in the “custom_components” folders, will that work? That would be one way to test…