search

mvalla / openhab-addons

Add-ons for openHAB
Eclipse Public License 2.0
24 stars 16 forks source link

Device Discovery is breaking the RFXCOM connection #61

Closed julesbike closed 5 years ago

julesbike commented 5 years ago

I am on the latest release of the binding and there seems to be a discovery conflict with the RFXCom binding. 

2019-03-04 09:16:06.003 [DEBUG] [ery.OpenWebNetBridgeDiscoveryService] - ==OWN:BridgeDiscovery== getSupportedThingTypes()
2019-03-04 09:16:06.004 [INFO ] [ery.OpenWebNetBridgeDiscoveryService] - ==OWN:BridgeDiscovery== ------ startScan() - SEARCHING for bridges...
2019-03-04 09:16:06.005 [DEBUG] [ery.OpenWebNetBridgeDiscoveryService] - ==OWN:BridgeDiscovery:Dongle== Gateway NULL, creating a new one ...
2019-03-04 09:16:06.006 [DEBUG] [ery.OpenWebNetBridgeDiscoveryService] - ==OWN:BridgeDiscovery:Dongle== ... trying to connect dongle ...
2019-03-04 09:16:06.007 [DEBUG] [org.openwebnet.OpenGatewayZigBee    ] - ##gw-openwebnet## Finding serial ports...
2019-03-04 09:16:06.017 [DEBUG] [org.openwebnet.OpenGatewayZigBee    ] - ##gw-openwebnet## ... found serial port /dev/ttyUSB0
2019-03-04 09:16:06.018 [DEBUG] [org.openwebnet.OpenGatewayZigBee    ] - ##gw-openwebnet## ... found serial port /dev/ttyS0
2019-03-04 09:16:06.018 [DEBUG] [org.openwebnet.OpenGatewayZigBee    ] - ##gw-openwebnet## ... trying to connect USB dongle on serial port /dev/ttyUSB0 ...

Then shortly afterwards the RFXCom binding loses it's connection:

2019-03-04 09:16:46.834 [DEBUG] [rnal.connector.RFXComSerialConnector] - Serial port event listener stopped
2019-03-04 09:16:46.835 [DEBUG] [rnal.connector.RFXComSerialConnector] - Interrupt serial listener
2019-03-04 09:16:46.835 [DEBUG] [rnal.connector.RFXComSerialConnector] - Close serial out stream
2019-03-04 09:16:46.835 [DEBUG] [rnal.connector.RFXComSerialConnector] - Close serial in stream
2019-03-04 09:16:46.835 [DEBUG] [rnal.connector.RFXComSerialConnector] - Close serial port
2019-03-04 09:16:46.846 [DEBUG] [rnal.connector.RFXComSerialConnector] - Closed
2019-03-04 09:16:48.847 [ERROR] [g.rfxcom.handler.RFXComBridgeHandler] - Connection to RFXCOM transceiver failed
gnu.io.PortInUseException: org.openwebnet.OpenGatewayZigBee
    at gnu.io.CommPortIdentifier.open(CommPortIdentifier.java:459) ~[?:?]
    at org.openhab.binding.rfxcom.internal.connector.RFXComSerialConnector.connect(RFXComSerialConnector.java:48) ~[?:?]
    at org.openhab.binding.rfxcom.handler.RFXComBridgeHandler.connect(RFXComBridgeHandler.java:175) ~[?:?]
    at org.openhab.binding.rfxcom.handler.RFXComBridgeHandler.lambda$0(RFXComBridgeHandler.java:149) ~[?:?]
    at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) [?:?]
    at java.util.concurrent.FutureTask.runAndReset(FutureTask.java:308) [?:?]
    at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$301(ScheduledThreadPoolExecutor.java:180) [?:?]
    at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:294) [?:?]
    at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) [?:?]
    at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) [?:?]
    at java.lang.Thread.run(Thread.java:748) [?:?]

So it looks like the Zigbee gateway (which I don't have) is blocking the USB port.

This only seems to happen if I open the PaperUI and do a Things search.

mvalla commented 5 years ago

can you post the complete log from when you start the discovery for openwebnet to the error: Connection to RFXCOM transceiver failed ?

julesbike commented 5 years ago

Here you go. Please note the binding works fine but if I do a discovery then I have to restart Openhab otherwise I lose the RFXcom connection.

2019-03-04 09:15:43.832 [DEBUG] [g.rfxcom.handler.RFXComBridgeHandler] - Checking RFXCOM transceiver connection, thing status = ONLINE
2019-03-04 09:15:51.128 [DEBUG] [ery.OpenWebNetDeviceDiscoveryService] - ==OWN:DeviceDiscovery== getSupportedThingTypes()
2019-03-04 09:15:51.129 [DEBUG] [ery.OpenWebNetBridgeDiscoveryService] - ==OWN:BridgeDiscovery== getSupportedThingTypes()
2019-03-04 09:15:51.161 [DEBUG] [ery.OpenWebNetDeviceDiscoveryService] - ==OWN:DeviceDiscovery== getSupportedThingTypes()
2019-03-04 09:15:51.161 [DEBUG] [ery.OpenWebNetBridgeDiscoveryService] - ==OWN:BridgeDiscovery== getSupportedThingTypes()
2019-03-04 09:15:51.219 [DEBUG] [ery.OpenWebNetDeviceDiscoveryService] - ==OWN:DeviceDiscovery== getSupportedThingTypes()
2019-03-04 09:15:51.220 [DEBUG] [ery.OpenWebNetBridgeDiscoveryService] - ==OWN:BridgeDiscovery== getSupportedThingTypes()
2019-03-04 09:15:51.222 [DEBUG] [ery.OpenWebNetDeviceDiscoveryService] - ==OWN:DeviceDiscovery== getSupportedThingTypes()
2019-03-04 09:15:51.222 [DEBUG] [ery.OpenWebNetDeviceDiscoveryService] - ==OWN:DeviceDiscovery== ------ stopScan()
2019-03-04 09:15:51.223 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN== -------- scanIsActive=false
2019-03-04 09:15:51.225 [INFO ] [ery.OpenWebNetDeviceDiscoveryService] - ==OWN:DeviceDiscovery== ------ startScan() - SEARCHING for DEVICES on bridge BUS Gateway (openwebnet:bus_gateway:4d3a0d8c)...
2019-03-04 09:15:51.225 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  -------- BridgeHandler.searchDevices()
2019-03-04 09:15:51.225 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN== -------- scanIsActive=true
2019-03-04 09:15:51.226 [INFO ] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN== -------- STARTED active search for devices on gateway 'BUS Gateway'
2019-03-04 09:15:51.226 [DEBUG] [org.openwebnet.OpenGatewayBus       ] - ##gw-openwebnet-BUS## ----- discoverDevices()
2019-03-04 09:15:51.227 [DEBUG] [org.openwebnet.OpenGatewayBus       ] - ##gw-openwebnet-BUS## ----- starting a new discovery...
2019-03-04 09:15:51.227 [INFO ] [g.openwebnet.bus.MyHomeJavaConnector] - COM SENDING   ============>>>>>>>>>>>> *#1*0##
2019-03-04 09:15:51.315 [INFO ] [g.openwebnet.bus.MyHomeSocketFactory] - COM RECEIVED  <<<<<<<<<<<<============ *1*0*11##
2019-03-04 09:15:51.317 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *1*0*11##
2019-03-04 09:15:51.318 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  ownId=1.11
2019-03-04 09:15:51.318 [DEBUG] [et.handler.OpenWebNetLightingHandler] - ==OWN:LightingHandler== updateLightState() for thing: openwebnet:bus_on_off_switch:4d3a0d8c:11
2019-03-04 09:15:51.385 [INFO ] [g.openwebnet.bus.MyHomeSocketFactory] - COM RECEIVED  <<<<<<<<<<<<============ *1*0*21##
2019-03-04 09:15:51.413 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *1*0*21##
2019-03-04 09:15:51.414 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  ownId=1.21
2019-03-04 09:15:51.414 [DEBUG] [et.handler.OpenWebNetLightingHandler] - ==OWN:LightingHandler== updateLightState() for thing: openwebnet:bus_on_off_switch:4d3a0d8c:21
2019-03-04 09:15:51.463 [INFO ] [g.openwebnet.bus.MyHomeSocketFactory] - COM RECEIVED  <<<<<<<<<<<<============ *1*0*12##
2019-03-04 09:15:51.466 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *1*0*12##
2019-03-04 09:15:51.467 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  ownId=1.12
2019-03-04 09:15:51.467 [DEBUG] [et.handler.OpenWebNetLightingHandler] - ==OWN:LightingHandler== updateLightState() for thing: openwebnet:bus_on_off_switch:4d3a0d8c:12
2019-03-04 09:15:51.517 [INFO ] [g.openwebnet.bus.MyHomeSocketFactory] - COM RECEIVED  <<<<<<<<<<<<============ *1*0*27##
2019-03-04 09:15:51.531 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *1*0*27##
2019-03-04 09:15:51.532 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  ownId=1.27
2019-03-04 09:15:51.532 [DEBUG] [et.handler.OpenWebNetLightingHandler] - ==OWN:LightingHandler== updateLightState() for thing: openwebnet:bus_on_off_switch:4d3a0d8c:27
2019-03-04 09:15:51.587 [INFO ] [g.openwebnet.bus.MyHomeSocketFactory] - COM RECEIVED  <<<<<<<<<<<<============ *1*0*81##
2019-03-04 09:15:51.610 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *1*0*81##
2019-03-04 09:15:51.611 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  ownId=1.81
2019-03-04 09:15:51.611 [DEBUG] [et.handler.OpenWebNetLightingHandler] - ==OWN:LightingHandler== updateLightState() for thing: openwebnet:bus_on_off_switch:4d3a0d8c:81
2019-03-04 09:15:51.646 [INFO ] [g.openwebnet.bus.MyHomeSocketFactory] - COM RECEIVED  <<<<<<<<<<<<============ *1*0*61##
2019-03-04 09:15:51.730 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *1*0*61##
2019-03-04 09:15:51.731 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  ownId=1.61
2019-03-04 09:15:51.731 [INFO ] [g.openwebnet.bus.MyHomeSocketFactory] - COM RECEIVED  <<<<<<<<<<<<============ *1*0*91##
2019-03-04 09:15:51.732 [DEBUG] [et.handler.OpenWebNetLightingHandler] - ==OWN:LightingHandler== updateLightState() for thing: openwebnet:bus_on_off_switch:4d3a0d8c:61
2019-03-04 09:15:51.736 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *1*0*91##
2019-03-04 09:15:51.737 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  ownId=1.91
2019-03-04 09:15:51.737 [DEBUG] [et.handler.OpenWebNetLightingHandler] - ==OWN:LightingHandler== updateLightState() for thing: openwebnet:bus_on_off_switch:4d3a0d8c:91
2019-03-04 09:15:51.804 [INFO ] [g.openwebnet.bus.MyHomeSocketFactory] - COM RECEIVED  <<<<<<<<<<<<============ *1*0*63##
2019-03-04 09:15:51.807 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *1*0*63##
2019-03-04 09:15:51.807 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  ownId=1.63
2019-03-04 09:15:51.808 [DEBUG] [et.handler.OpenWebNetLightingHandler] - ==OWN:LightingHandler== updateLightState() for thing: openwebnet:bus_on_off_switch:4d3a0d8c:63
2019-03-04 09:15:51.893 [INFO ] [g.openwebnet.bus.MyHomeSocketFactory] - COM RECEIVED  <<<<<<<<<<<<============ *1*1*22##
2019-03-04 09:15:51.920 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *1*1*22##
2019-03-04 09:15:51.921 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  ownId=1.22
2019-03-04 09:15:51.921 [DEBUG] [et.handler.OpenWebNetLightingHandler] - ==OWN:LightingHandler== updateLightState() for thing: openwebnet:bus_on_off_switch:4d3a0d8c:22
2019-03-04 09:15:51.954 [INFO ] [g.openwebnet.bus.MyHomeSocketFactory] - COM RECEIVED  <<<<<<<<<<<<============ *1*0*42##
2019-03-04 09:15:51.957 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *1*0*42##
2019-03-04 09:15:51.957 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  ownId=1.42
2019-03-04 09:15:51.958 [DEBUG] [et.handler.OpenWebNetLightingHandler] - ==OWN:LightingHandler== updateLightState() for thing: openwebnet:bus_on_off_switch:4d3a0d8c:42
2019-03-04 09:15:52.025 [INFO ] [g.openwebnet.bus.MyHomeSocketFactory] - COM RECEIVED  <<<<<<<<<<<<============ *1*0*23##
2019-03-04 09:15:52.027 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *1*0*23##
2019-03-04 09:15:52.028 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  ownId=1.23
2019-03-04 09:15:52.028 [DEBUG] [et.handler.OpenWebNetLightingHandler] - ==OWN:LightingHandler== updateLightState() for thing: openwebnet:bus_on_off_switch:4d3a0d8c:23
2019-03-04 09:15:52.095 [INFO ] [g.openwebnet.bus.MyHomeSocketFactory] - COM RECEIVED  <<<<<<<<<<<<============ *1*0*43##
2019-03-04 09:15:52.097 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *1*0*43##
2019-03-04 09:15:52.098 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  ownId=1.43
2019-03-04 09:15:52.098 [DEBUG] [et.handler.OpenWebNetLightingHandler] - ==OWN:LightingHandler== updateLightState() for thing: openwebnet:bus_on_off_switch:4d3a0d8c:43
2019-03-04 09:15:52.155 [INFO ] [g.openwebnet.bus.MyHomeSocketFactory] - COM RECEIVED  <<<<<<<<<<<<============ *1*0*52##
2019-03-04 09:15:52.170 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *1*0*52##
2019-03-04 09:15:52.170 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  ownId=1.52
2019-03-04 09:15:52.171 [DEBUG] [et.handler.OpenWebNetLightingHandler] - ==OWN:LightingHandler== updateLightState() for thing: openwebnet:bus_on_off_switch:4d3a0d8c:52
2019-03-04 09:15:52.233 [INFO ] [g.openwebnet.bus.MyHomeSocketFactory] - COM RECEIVED  <<<<<<<<<<<<============ *1*0*13##
2019-03-04 09:15:52.236 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *1*0*13##
2019-03-04 09:15:52.236 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  ownId=1.13
2019-03-04 09:15:52.237 [DEBUG] [et.handler.OpenWebNetLightingHandler] - ==OWN:LightingHandler== updateLightState() for thing: openwebnet:bus_on_off_switch:4d3a0d8c:13
2019-03-04 09:15:52.305 [INFO ] [g.openwebnet.bus.MyHomeSocketFactory] - COM RECEIVED  <<<<<<<<<<<<============ *1*0*54##
2019-03-04 09:15:52.307 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *1*0*54##
2019-03-04 09:15:52.308 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  ownId=1.54
2019-03-04 09:15:52.308 [DEBUG] [et.handler.OpenWebNetLightingHandler] - ==OWN:LightingHandler== updateLightState() for thing: openwebnet:bus_on_off_switch:4d3a0d8c:54
2019-03-04 09:15:52.378 [INFO ] [g.openwebnet.bus.MyHomeSocketFactory] - COM RECEIVED  <<<<<<<<<<<<============ *1*0*17##
2019-03-04 09:15:52.414 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *1*0*17##
2019-03-04 09:15:52.414 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  ownId=1.17
2019-03-04 09:15:52.415 [DEBUG] [et.handler.OpenWebNetLightingHandler] - ==OWN:LightingHandler== updateLightState() for thing: openwebnet:bus_on_off_switch:4d3a0d8c:17
2019-03-04 09:15:52.438 [INFO ] [g.openwebnet.bus.MyHomeSocketFactory] - COM RECEIVED  <<<<<<<<<<<<============ *1*0*33##
2019-03-04 09:15:52.487 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *1*0*33##
2019-03-04 09:15:52.488 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  ownId=1.33
2019-03-04 09:15:52.488 [DEBUG] [et.handler.OpenWebNetLightingHandler] - ==OWN:LightingHandler== updateLightState() for thing: openwebnet:bus_on_off_switch:4d3a0d8c:33
2019-03-04 09:15:52.541 [INFO ] [g.openwebnet.bus.MyHomeSocketFactory] - COM RECEIVED  <<<<<<<<<<<<============ *1*0*14##
2019-03-04 09:15:52.546 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *1*0*14##
2019-03-04 09:15:52.547 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  ownId=1.14
2019-03-04 09:15:52.547 [DEBUG] [et.handler.OpenWebNetLightingHandler] - ==OWN:LightingHandler== updateLightState() for thing: openwebnet:bus_on_off_switch:4d3a0d8c:14
2019-03-04 09:15:52.566 [INFO ] [g.openwebnet.bus.MyHomeSocketFactory] - COM RECEIVED  <<<<<<<<<<<<============ *1*0*34##
2019-03-04 09:15:52.597 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *1*0*34##
2019-03-04 09:15:52.597 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  ownId=1.34
2019-03-04 09:15:52.598 [DEBUG] [et.handler.OpenWebNetLightingHandler] - ==OWN:LightingHandler== updateLightState() for thing: openwebnet:bus_on_off_switch:4d3a0d8c:34
2019-03-04 09:15:52.638 [INFO ] [g.openwebnet.bus.MyHomeSocketFactory] - COM RECEIVED  <<<<<<<<<<<<============ *1*0*15##
2019-03-04 09:15:52.691 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *1*0*15##
2019-03-04 09:15:52.692 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  ownId=1.15
2019-03-04 09:15:52.692 [DEBUG] [et.handler.OpenWebNetLightingHandler] - ==OWN:LightingHandler== updateLightState() for thing: openwebnet:bus_on_off_switch:4d3a0d8c:15
2019-03-04 09:15:52.729 [INFO ] [g.openwebnet.bus.MyHomeSocketFactory] - COM RECEIVED  <<<<<<<<<<<<============ *1*0*44##
2019-03-04 09:15:52.732 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *1*0*44##
2019-03-04 09:15:52.733 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  ownId=1.44
2019-03-04 09:15:52.733 [DEBUG] [et.handler.OpenWebNetLightingHandler] - ==OWN:LightingHandler== updateLightState() for thing: openwebnet:bus_on_off_switch:4d3a0d8c:44
2019-03-04 09:15:52.819 [INFO ] [g.openwebnet.bus.MyHomeSocketFactory] - COM RECEIVED  <<<<<<<<<<<<============ *1*0*25##
2019-03-04 09:15:52.824 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *1*0*25##
2019-03-04 09:15:52.825 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  ownId=1.25
2019-03-04 09:15:52.825 [DEBUG] [et.handler.OpenWebNetLightingHandler] - ==OWN:LightingHandler== updateLightState() for thing: openwebnet:bus_on_off_switch:4d3a0d8c:25
2019-03-04 09:15:52.832 [INFO ] [g.openwebnet.bus.MyHomeSocketFactory] - COM RECEIVED  <<<<<<<<<<<<============ *1*0*45##
2019-03-04 09:15:52.836 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *1*0*45##
2019-03-04 09:15:52.837 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  ownId=1.45
2019-03-04 09:15:52.837 [DEBUG] [et.handler.OpenWebNetLightingHandler] - ==OWN:LightingHandler== updateLightState() for thing: openwebnet:bus_on_off_switch:4d3a0d8c:45
2019-03-04 09:15:52.925 [INFO ] [g.openwebnet.bus.MyHomeSocketFactory] - COM RECEIVED  <<<<<<<<<<<<============ *1*0*29##
2019-03-04 09:15:52.927 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *1*0*29##
2019-03-04 09:15:52.928 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  ownId=1.29
2019-03-04 09:15:52.928 [DEBUG] [et.handler.OpenWebNetLightingHandler] - ==OWN:LightingHandler== updateLightState() for thing: openwebnet:bus_on_off_switch:4d3a0d8c:29
2019-03-04 09:15:52.978 [INFO ] [g.openwebnet.bus.MyHomeSocketFactory] - COM RECEIVED  <<<<<<<<<<<<============ *1*0*64##
2019-03-04 09:15:53.108 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *1*0*64##
2019-03-04 09:15:53.109 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  ownId=1.64
2019-03-04 09:15:53.110 [DEBUG] [et.handler.OpenWebNetLightingHandler] - ==OWN:LightingHandler== updateLightState() for thing: openwebnet:bus_on_off_switch:4d3a0d8c:64
2019-03-04 09:15:53.109 [INFO ] [g.openwebnet.bus.MyHomeSocketFactory] - COM RECEIVED  <<<<<<<<<<<<============ *1*0*26##
2019-03-04 09:15:53.111 [INFO ] [g.openwebnet.bus.MyHomeSocketFactory] - COM RECEIVED  <<<<<<<<<<<<============ *1*0*65##
2019-03-04 09:15:53.111 [INFO ] [g.openwebnet.bus.MyHomeSocketFactory] - COM RECEIVED  <<<<<<<<<<<<============ *1*0*24##
2019-03-04 09:15:53.112 [INFO ] [g.openwebnet.bus.MyHomeSocketFactory] - COM RECEIVED  <<<<<<<<<<<<============ *1*0*55##
2019-03-04 09:15:53.114 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *1*0*26##
2019-03-04 09:15:53.115 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  ownId=1.26
2019-03-04 09:15:53.115 [DEBUG] [et.handler.OpenWebNetLightingHandler] - ==OWN:LightingHandler== updateLightState() for thing: openwebnet:bus_on_off_switch:4d3a0d8c:26
2019-03-04 09:15:53.118 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *1*0*65##
2019-03-04 09:15:53.119 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  ownId=1.65
2019-03-04 09:15:53.119 [DEBUG] [et.handler.OpenWebNetLightingHandler] - ==OWN:LightingHandler== updateLightState() for thing: openwebnet:bus_on_off_switch:4d3a0d8c:65
2019-03-04 09:15:53.120 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *1*0*24##
2019-03-04 09:15:53.121 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  ownId=1.24
2019-03-04 09:15:53.121 [DEBUG] [et.handler.OpenWebNetLightingHandler] - ==OWN:LightingHandler== updateLightState() for thing: openwebnet:bus_on_off_switch:4d3a0d8c:24
2019-03-04 09:15:53.130 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *1*0*55##
2019-03-04 09:15:53.130 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  ownId=1.55
2019-03-04 09:15:53.131 [DEBUG] [et.handler.OpenWebNetLightingHandler] - ==OWN:LightingHandler== updateLightState() for thing: openwebnet:bus_on_off_switch:4d3a0d8c:55
2019-03-04 09:15:53.133 [INFO ] [g.openwebnet.bus.MyHomeSocketFactory] - COM RECEIVED  <<<<<<<<<<<<============ *1*0*16##
2019-03-04 09:15:53.136 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *1*0*16##
2019-03-04 09:15:53.136 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  ownId=1.16
2019-03-04 09:15:53.137 [DEBUG] [et.handler.OpenWebNetLightingHandler] - ==OWN:LightingHandler== updateLightState() for thing: openwebnet:bus_on_off_switch:4d3a0d8c:16
2019-03-04 09:15:53.153 [INFO ] [g.openwebnet.bus.MyHomeSocketFactory] - COM RECEIVED  <<<<<<<<<<<<============ *1*1*36##
2019-03-04 09:15:53.154 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *1*1*36##
2019-03-04 09:15:53.155 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  ownId=1.36
2019-03-04 09:15:53.156 [DEBUG] [et.handler.OpenWebNetLightingHandler] - ==OWN:LightingHandler== updateLightState() for thing: openwebnet:bus_on_off_switch:4d3a0d8c:36
2019-03-04 09:15:53.217 [INFO ] [g.openwebnet.bus.MyHomeSocketFactory] - COM RECEIVED  <<<<<<<<<<<<============ *1*0*18##
2019-03-04 09:15:53.279 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *1*0*18##
2019-03-04 09:15:53.279 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  ownId=1.18
2019-03-04 09:15:53.280 [DEBUG] [et.handler.OpenWebNetLightingHandler] - ==OWN:LightingHandler== updateLightState() for thing: openwebnet:bus_on_off_switch:4d3a0d8c:18
2019-03-04 09:15:53.285 [INFO ] [g.openwebnet.bus.MyHomeSocketFactory] - COM RECEIVED  <<<<<<<<<<<<============ *1*0*32##
2019-03-04 09:15:53.287 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *1*0*32##
2019-03-04 09:15:53.288 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  ownId=1.32
2019-03-04 09:15:53.288 [DEBUG] [et.handler.OpenWebNetLightingHandler] - ==OWN:LightingHandler== updateLightState() for thing: openwebnet:bus_on_off_switch:4d3a0d8c:32
2019-03-04 09:15:53.380 [INFO ] [g.openwebnet.bus.MyHomeSocketFactory] - COM RECEIVED  <<<<<<<<<<<<============ *1*0*19##
2019-03-04 09:15:53.383 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *1*0*19##
2019-03-04 09:15:53.383 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  ownId=1.19
2019-03-04 09:15:53.384 [DEBUG] [et.handler.OpenWebNetLightingHandler] - ==OWN:LightingHandler== updateLightState() for thing: openwebnet:bus_on_off_switch:4d3a0d8c:19
2019-03-04 09:15:53.420 [INFO ] [g.openwebnet.bus.MyHomeSocketFactory] - COM RECEIVED  <<<<<<<<<<<<============ *1*0*46##
2019-03-04 09:15:53.423 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *1*0*46##
2019-03-04 09:15:53.424 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  ownId=1.46
2019-03-04 09:15:53.424 [DEBUG] [et.handler.OpenWebNetLightingHandler] - ==OWN:LightingHandler== updateLightState() for thing: openwebnet:bus_on_off_switch:4d3a0d8c:46
2019-03-04 09:15:53.460 [INFO ] [g.openwebnet.bus.MyHomeSocketFactory] - COM RECEIVED  <<<<<<<<<<<<============ *1*0*47##
2019-03-04 09:15:53.463 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *1*0*47##
2019-03-04 09:15:53.464 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  ownId=1.47
2019-03-04 09:15:53.464 [DEBUG] [et.handler.OpenWebNetLightingHandler] - ==OWN:LightingHandler== updateLightState() for thing: openwebnet:bus_on_off_switch:4d3a0d8c:47
2019-03-04 09:15:53.512 [INFO ] [g.openwebnet.bus.MyHomeSocketFactory] - COM RECEIVED  <<<<<<<<<<<<============ *1*0*56##
2019-03-04 09:15:53.516 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *1*0*56##
2019-03-04 09:15:53.516 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  ownId=1.56
2019-03-04 09:15:53.517 [DEBUG] [et.handler.OpenWebNetLightingHandler] - ==OWN:LightingHandler== updateLightState() for thing: openwebnet:bus_on_off_switch:4d3a0d8c:56
2019-03-04 09:15:53.522 [INFO ] [g.openwebnet.bus.MyHomeSocketFactory] - COM RECEIVED  <<<<<<<<<<<<============ *1*0*57##
2019-03-04 09:15:53.524 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *1*0*57##
2019-03-04 09:15:53.525 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  ownId=1.57
2019-03-04 09:15:53.526 [DEBUG] [et.handler.OpenWebNetLightingHandler] - ==OWN:LightingHandler== updateLightState() for thing: openwebnet:bus_on_off_switch:4d3a0d8c:57
2019-03-04 09:15:53.649 [INFO ] [g.openwebnet.bus.MyHomeSocketFactory] - COM RECEIVED  <<<<<<<<<<<<============ *1*0*48##
2019-03-04 09:15:53.652 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *1*0*48##
2019-03-04 09:15:53.653 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  ownId=1.48
2019-03-04 09:15:53.653 [DEBUG] [et.handler.OpenWebNetLightingHandler] - ==OWN:LightingHandler== updateLightState() for thing: openwebnet:bus_on_off_switch:4d3a0d8c:48
2019-03-04 09:15:53.699 [INFO ] [g.openwebnet.bus.MyHomeSocketFactory] - COM RECEIVED  <<<<<<<<<<<<============ *1*0*49##
2019-03-04 09:15:53.702 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *1*0*49##
2019-03-04 09:15:53.703 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  ownId=1.49
2019-03-04 09:15:53.703 [DEBUG] [et.handler.OpenWebNetLightingHandler] - ==OWN:LightingHandler== updateLightState() for thing: openwebnet:bus_on_off_switch:4d3a0d8c:49
2019-03-04 09:15:53.729 [INFO ] [g.openwebnet.bus.MyHomeSocketFactory] - COM RECEIVED  <<<<<<<<<<<<============ *1*0*59##
2019-03-04 09:15:53.732 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *1*0*59##
2019-03-04 09:15:53.732 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  ownId=1.59
2019-03-04 09:15:53.733 [DEBUG] [et.handler.OpenWebNetLightingHandler] - ==OWN:LightingHandler== updateLightState() for thing: openwebnet:bus_on_off_switch:4d3a0d8c:59
2019-03-04 09:15:53.739 [INFO ] [g.openwebnet.bus.MyHomeSocketFactory] - COM RECEIVED  <<<<<<<<<<<<============ *1*0*53##
2019-03-04 09:15:53.760 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *1*0*53##
2019-03-04 09:15:53.761 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  ownId=1.53
2019-03-04 09:15:53.761 [DEBUG] [et.handler.OpenWebNetLightingHandler] - ==OWN:LightingHandler== updateLightState() for thing: openwebnet:bus_on_off_switch:4d3a0d8c:53
2019-03-04 09:15:54.739 [INFO ] [g.openwebnet.bus.MyHomeSocketFactory] - COM RECEIVED  <<<<<<<<<<<<============ *#*1##
2019-03-04 09:15:55.043 [INFO ] [ery.OpenWebNetDeviceDiscoveryService] - ==OWN:DeviceDiscovery== newDiscoveryResult() WHERE=11, deviceType=SCS_ON_OFF_SWITCH
2019-03-04 09:15:55.044 [INFO ] [ery.OpenWebNetDeviceDiscoveryService] - ==OWN:DeviceDiscovery== newDiscoveryResult() WHERE=21, deviceType=SCS_ON_OFF_SWITCH
2019-03-04 09:15:55.045 [INFO ] [ery.OpenWebNetDeviceDiscoveryService] - ==OWN:DeviceDiscovery== newDiscoveryResult() WHERE=12, deviceType=SCS_ON_OFF_SWITCH
2019-03-04 09:15:55.046 [INFO ] [ery.OpenWebNetDeviceDiscoveryService] - ==OWN:DeviceDiscovery== newDiscoveryResult() WHERE=27, deviceType=SCS_ON_OFF_SWITCH
2019-03-04 09:15:55.047 [INFO ] [ery.OpenWebNetDeviceDiscoveryService] - ==OWN:DeviceDiscovery== newDiscoveryResult() WHERE=81, deviceType=SCS_ON_OFF_SWITCH
2019-03-04 09:15:55.047 [INFO ] [ery.OpenWebNetDeviceDiscoveryService] - ==OWN:DeviceDiscovery== newDiscoveryResult() WHERE=61, deviceType=SCS_ON_OFF_SWITCH
2019-03-04 09:15:55.048 [INFO ] [ery.OpenWebNetDeviceDiscoveryService] - ==OWN:DeviceDiscovery== newDiscoveryResult() WHERE=91, deviceType=SCS_ON_OFF_SWITCH
2019-03-04 09:15:55.049 [INFO ] [ery.OpenWebNetDeviceDiscoveryService] - ==OWN:DeviceDiscovery== newDiscoveryResult() WHERE=63, deviceType=SCS_ON_OFF_SWITCH
2019-03-04 09:15:55.050 [INFO ] [ery.OpenWebNetDeviceDiscoveryService] - ==OWN:DeviceDiscovery== newDiscoveryResult() WHERE=22, deviceType=SCS_ON_OFF_SWITCH
2019-03-04 09:15:55.051 [INFO ] [ery.OpenWebNetDeviceDiscoveryService] - ==OWN:DeviceDiscovery== newDiscoveryResult() WHERE=42, deviceType=SCS_ON_OFF_SWITCH
2019-03-04 09:15:55.052 [INFO ] [ery.OpenWebNetDeviceDiscoveryService] - ==OWN:DeviceDiscovery== newDiscoveryResult() WHERE=23, deviceType=SCS_ON_OFF_SWITCH
2019-03-04 09:15:55.052 [INFO ] [ery.OpenWebNetDeviceDiscoveryService] - ==OWN:DeviceDiscovery== newDiscoveryResult() WHERE=43, deviceType=SCS_ON_OFF_SWITCH
2019-03-04 09:15:55.053 [INFO ] [ery.OpenWebNetDeviceDiscoveryService] - ==OWN:DeviceDiscovery== newDiscoveryResult() WHERE=52, deviceType=SCS_ON_OFF_SWITCH
2019-03-04 09:15:55.054 [INFO ] [ery.OpenWebNetDeviceDiscoveryService] - ==OWN:DeviceDiscovery== newDiscoveryResult() WHERE=13, deviceType=SCS_ON_OFF_SWITCH
2019-03-04 09:15:55.055 [INFO ] [ery.OpenWebNetDeviceDiscoveryService] - ==OWN:DeviceDiscovery== newDiscoveryResult() WHERE=54, deviceType=SCS_ON_OFF_SWITCH
2019-03-04 09:15:55.056 [INFO ] [ery.OpenWebNetDeviceDiscoveryService] - ==OWN:DeviceDiscovery== newDiscoveryResult() WHERE=17, deviceType=SCS_ON_OFF_SWITCH
2019-03-04 09:15:55.057 [INFO ] [ery.OpenWebNetDeviceDiscoveryService] - ==OWN:DeviceDiscovery== newDiscoveryResult() WHERE=33, deviceType=SCS_ON_OFF_SWITCH
2019-03-04 09:15:55.057 [INFO ] [ery.OpenWebNetDeviceDiscoveryService] - ==OWN:DeviceDiscovery== newDiscoveryResult() WHERE=14, deviceType=SCS_ON_OFF_SWITCH
2019-03-04 09:15:55.058 [INFO ] [ery.OpenWebNetDeviceDiscoveryService] - ==OWN:DeviceDiscovery== newDiscoveryResult() WHERE=34, deviceType=SCS_ON_OFF_SWITCH
2019-03-04 09:15:55.059 [INFO ] [ery.OpenWebNetDeviceDiscoveryService] - ==OWN:DeviceDiscovery== newDiscoveryResult() WHERE=15, deviceType=SCS_ON_OFF_SWITCH
2019-03-04 09:15:55.060 [INFO ] [ery.OpenWebNetDeviceDiscoveryService] - ==OWN:DeviceDiscovery== newDiscoveryResult() WHERE=44, deviceType=SCS_ON_OFF_SWITCH
2019-03-04 09:15:55.061 [INFO ] [ery.OpenWebNetDeviceDiscoveryService] - ==OWN:DeviceDiscovery== newDiscoveryResult() WHERE=25, deviceType=SCS_ON_OFF_SWITCH
2019-03-04 09:15:55.061 [INFO ] [ery.OpenWebNetDeviceDiscoveryService] - ==OWN:DeviceDiscovery== newDiscoveryResult() WHERE=45, deviceType=SCS_ON_OFF_SWITCH
2019-03-04 09:15:55.062 [INFO ] [ery.OpenWebNetDeviceDiscoveryService] - ==OWN:DeviceDiscovery== newDiscoveryResult() WHERE=29, deviceType=SCS_ON_OFF_SWITCH
2019-03-04 09:15:55.063 [INFO ] [ery.OpenWebNetDeviceDiscoveryService] - ==OWN:DeviceDiscovery== newDiscoveryResult() WHERE=64, deviceType=SCS_ON_OFF_SWITCH
2019-03-04 09:15:55.064 [INFO ] [ery.OpenWebNetDeviceDiscoveryService] - ==OWN:DeviceDiscovery== newDiscoveryResult() WHERE=26, deviceType=SCS_ON_OFF_SWITCH
2019-03-04 09:15:55.065 [INFO ] [ery.OpenWebNetDeviceDiscoveryService] - ==OWN:DeviceDiscovery== newDiscoveryResult() WHERE=65, deviceType=SCS_ON_OFF_SWITCH
2019-03-04 09:15:55.066 [INFO ] [ery.OpenWebNetDeviceDiscoveryService] - ==OWN:DeviceDiscovery== newDiscoveryResult() WHERE=24, deviceType=SCS_ON_OFF_SWITCH
2019-03-04 09:15:55.066 [INFO ] [ery.OpenWebNetDeviceDiscoveryService] - ==OWN:DeviceDiscovery== newDiscoveryResult() WHERE=55, deviceType=SCS_ON_OFF_SWITCH
2019-03-04 09:15:55.067 [INFO ] [ery.OpenWebNetDeviceDiscoveryService] - ==OWN:DeviceDiscovery== newDiscoveryResult() WHERE=16, deviceType=SCS_ON_OFF_SWITCH
2019-03-04 09:15:55.068 [INFO ] [ery.OpenWebNetDeviceDiscoveryService] - ==OWN:DeviceDiscovery== newDiscoveryResult() WHERE=36, deviceType=SCS_ON_OFF_SWITCH
2019-03-04 09:15:55.069 [INFO ] [ery.OpenWebNetDeviceDiscoveryService] - ==OWN:DeviceDiscovery== newDiscoveryResult() WHERE=18, deviceType=SCS_ON_OFF_SWITCH
2019-03-04 09:15:55.070 [INFO ] [ery.OpenWebNetDeviceDiscoveryService] - ==OWN:DeviceDiscovery== newDiscoveryResult() WHERE=32, deviceType=SCS_ON_OFF_SWITCH
2019-03-04 09:15:55.071 [INFO ] [ery.OpenWebNetDeviceDiscoveryService] - ==OWN:DeviceDiscovery== newDiscoveryResult() WHERE=19, deviceType=SCS_ON_OFF_SWITCH
2019-03-04 09:15:55.071 [INFO ] [ery.OpenWebNetDeviceDiscoveryService] - ==OWN:DeviceDiscovery== newDiscoveryResult() WHERE=46, deviceType=SCS_ON_OFF_SWITCH
2019-03-04 09:15:55.072 [INFO ] [ery.OpenWebNetDeviceDiscoveryService] - ==OWN:DeviceDiscovery== newDiscoveryResult() WHERE=47, deviceType=SCS_ON_OFF_SWITCH
2019-03-04 09:15:55.073 [INFO ] [ery.OpenWebNetDeviceDiscoveryService] - ==OWN:DeviceDiscovery== newDiscoveryResult() WHERE=56, deviceType=SCS_ON_OFF_SWITCH
2019-03-04 09:15:55.074 [INFO ] [ery.OpenWebNetDeviceDiscoveryService] - ==OWN:DeviceDiscovery== newDiscoveryResult() WHERE=57, deviceType=SCS_ON_OFF_SWITCH
2019-03-04 09:15:55.075 [INFO ] [ery.OpenWebNetDeviceDiscoveryService] - ==OWN:DeviceDiscovery== newDiscoveryResult() WHERE=48, deviceType=SCS_ON_OFF_SWITCH
2019-03-04 09:15:55.075 [INFO ] [ery.OpenWebNetDeviceDiscoveryService] - ==OWN:DeviceDiscovery== newDiscoveryResult() WHERE=49, deviceType=SCS_ON_OFF_SWITCH
2019-03-04 09:15:55.076 [INFO ] [ery.OpenWebNetDeviceDiscoveryService] - ==OWN:DeviceDiscovery== newDiscoveryResult() WHERE=59, deviceType=SCS_ON_OFF_SWITCH
2019-03-04 09:15:55.077 [INFO ] [ery.OpenWebNetDeviceDiscoveryService] - ==OWN:DeviceDiscovery== newDiscoveryResult() WHERE=53, deviceType=SCS_ON_OFF_SWITCH
2019-03-04 09:15:55.078 [INFO ] [g.openwebnet.bus.MyHomeJavaConnector] - COM SENDING   ============>>>>>>>>>>>> *#2*0##
2019-03-04 09:15:55.166 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *1*0*11##
2019-03-04 09:15:55.166 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  ownId=1.11
2019-03-04 09:15:55.167 [DEBUG] [et.handler.OpenWebNetLightingHandler] - ==OWN:LightingHandler== updateLightState() for thing: openwebnet:bus_on_off_switch:4d3a0d8c:11
2019-03-04 09:15:55.240 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *1*0*61##
2019-03-04 09:15:55.241 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  ownId=1.61
2019-03-04 09:15:55.241 [DEBUG] [et.handler.OpenWebNetLightingHandler] - ==OWN:LightingHandler== updateLightState() for thing: openwebnet:bus_on_off_switch:4d3a0d8c:61
2019-03-04 09:15:55.296 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *1*0*12##
2019-03-04 09:15:55.296 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  ownId=1.12
2019-03-04 09:15:55.297 [DEBUG] [et.handler.OpenWebNetLightingHandler] - ==OWN:LightingHandler== updateLightState() for thing: openwebnet:bus_on_off_switch:4d3a0d8c:12
2019-03-04 09:15:55.375 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *1*0*21##
2019-03-04 09:15:55.376 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  ownId=1.21
2019-03-04 09:15:55.376 [DEBUG] [et.handler.OpenWebNetLightingHandler] - ==OWN:LightingHandler== updateLightState() for thing: openwebnet:bus_on_off_switch:4d3a0d8c:21
2019-03-04 09:15:55.427 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *1*0*63##
2019-03-04 09:15:55.427 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  ownId=1.63
2019-03-04 09:15:55.428 [DEBUG] [et.handler.OpenWebNetLightingHandler] - ==OWN:LightingHandler== updateLightState() for thing: openwebnet:bus_on_off_switch:4d3a0d8c:63
2019-03-04 09:15:55.487 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *1*0*27##
2019-03-04 09:15:55.488 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  ownId=1.27
2019-03-04 09:15:55.489 [DEBUG] [et.handler.OpenWebNetLightingHandler] - ==OWN:LightingHandler== updateLightState() for thing: openwebnet:bus_on_off_switch:4d3a0d8c:27
2019-03-04 09:15:55.547 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *1*0*81##
2019-03-04 09:15:55.548 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  ownId=1.81
2019-03-04 09:15:55.548 [DEBUG] [et.handler.OpenWebNetLightingHandler] - ==OWN:LightingHandler== updateLightState() for thing: openwebnet:bus_on_off_switch:4d3a0d8c:81
2019-03-04 09:15:55.624 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *1*0*91##
2019-03-04 09:15:55.625 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  ownId=1.91
2019-03-04 09:15:55.625 [DEBUG] [et.handler.OpenWebNetLightingHandler] - ==OWN:LightingHandler== updateLightState() for thing: openwebnet:bus_on_off_switch:4d3a0d8c:91
2019-03-04 09:15:55.675 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *1*1*22##
2019-03-04 09:15:55.675 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  ownId=1.22
2019-03-04 09:15:55.676 [DEBUG] [et.handler.OpenWebNetLightingHandler] - ==OWN:LightingHandler== updateLightState() for thing: openwebnet:bus_on_off_switch:4d3a0d8c:22
2019-03-04 09:15:55.717 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *1*0*42##
2019-03-04 09:15:55.718 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  ownId=1.42
2019-03-04 09:15:55.718 [DEBUG] [et.handler.OpenWebNetLightingHandler] - ==OWN:LightingHandler== updateLightState() for thing: openwebnet:bus_on_off_switch:4d3a0d8c:42
2019-03-04 09:15:55.840 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *1*0*52##
2019-03-04 09:15:55.841 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  ownId=1.52
2019-03-04 09:15:55.842 [DEBUG] [et.handler.OpenWebNetLightingHandler] - ==OWN:LightingHandler== updateLightState() for thing: openwebnet:bus_on_off_switch:4d3a0d8c:52
2019-03-04 09:15:55.861 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *1*0*23##
2019-03-04 09:15:55.862 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  ownId=1.23
2019-03-04 09:15:55.862 [DEBUG] [et.handler.OpenWebNetLightingHandler] - ==OWN:LightingHandler== updateLightState() for thing: openwebnet:bus_on_off_switch:4d3a0d8c:23
2019-03-04 09:15:55.944 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *1*0*43##
2019-03-04 09:15:55.945 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  ownId=1.43
2019-03-04 09:15:55.945 [DEBUG] [et.handler.OpenWebNetLightingHandler] - ==OWN:LightingHandler== updateLightState() for thing: openwebnet:bus_on_off_switch:4d3a0d8c:43
2019-03-04 09:15:56.011 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *1*0*54##
2019-03-04 09:15:56.012 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  ownId=1.54
2019-03-04 09:15:56.012 [DEBUG] [et.handler.OpenWebNetLightingHandler] - ==OWN:LightingHandler== updateLightState() for thing: openwebnet:bus_on_off_switch:4d3a0d8c:54
2019-03-04 09:15:56.026 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *1*0*13##
2019-03-04 09:15:56.027 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  ownId=1.13
2019-03-04 09:15:56.027 [DEBUG] [et.handler.OpenWebNetLightingHandler] - ==OWN:LightingHandler== updateLightState() for thing: openwebnet:bus_on_off_switch:4d3a0d8c:13
2019-03-04 09:15:56.090 [INFO ] [g.openwebnet.bus.MyHomeSocketFactory] - COM RECEIVED  <<<<<<<<<<<<============ *#*1##
2019-03-04 09:15:56.095 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *1*0*33##
2019-03-04 09:15:56.096 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  ownId=1.33
2019-03-04 09:15:56.096 [DEBUG] [et.handler.OpenWebNetLightingHandler] - ==OWN:LightingHandler== updateLightState() for thing: openwebnet:bus_on_off_switch:4d3a0d8c:33
2019-03-04 09:15:56.175 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *1*0*17##
2019-03-04 09:15:56.176 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  ownId=1.17
2019-03-04 09:15:56.176 [DEBUG] [et.handler.OpenWebNetLightingHandler] - ==OWN:LightingHandler== updateLightState() for thing: openwebnet:bus_on_off_switch:4d3a0d8c:17
2019-03-04 09:15:56.210 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *1*0*34##
2019-03-04 09:15:56.211 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  ownId=1.34
2019-03-04 09:15:56.211 [DEBUG] [et.handler.OpenWebNetLightingHandler] - ==OWN:LightingHandler== updateLightState() for thing: openwebnet:bus_on_off_switch:4d3a0d8c:34
2019-03-04 09:15:56.306 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *1*0*14##
2019-03-04 09:15:56.306 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  ownId=1.14
2019-03-04 09:15:56.307 [DEBUG] [et.handler.OpenWebNetLightingHandler] - ==OWN:LightingHandler== updateLightState() for thing: openwebnet:bus_on_off_switch:4d3a0d8c:14
2019-03-04 09:15:56.373 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *1*0*15##
2019-03-04 09:15:56.374 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  ownId=1.15
2019-03-04 09:15:56.374 [DEBUG] [et.handler.OpenWebNetLightingHandler] - ==OWN:LightingHandler== updateLightState() for thing: openwebnet:bus_on_off_switch:4d3a0d8c:15
2019-03-04 09:15:56.391 [INFO ] [g.openwebnet.bus.MyHomeJavaConnector] - COM SENDING   ============>>>>>>>>>>>> *#1004*0*7##
2019-03-04 09:15:56.460 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *1*0*44##
2019-03-04 09:15:56.460 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  ownId=1.44
2019-03-04 09:15:56.461 [DEBUG] [et.handler.OpenWebNetLightingHandler] - ==OWN:LightingHandler== updateLightState() for thing: openwebnet:bus_on_off_switch:4d3a0d8c:44
2019-03-04 09:15:56.564 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *1*0*25##
2019-03-04 09:15:56.565 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  ownId=1.25
2019-03-04 09:15:56.565 [DEBUG] [et.handler.OpenWebNetLightingHandler] - ==OWN:LightingHandler== updateLightState() for thing: openwebnet:bus_on_off_switch:4d3a0d8c:25
2019-03-04 09:15:56.612 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *1*0*45##
2019-03-04 09:15:56.613 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  ownId=1.45
2019-03-04 09:15:56.614 [DEBUG] [et.handler.OpenWebNetLightingHandler] - ==OWN:LightingHandler== updateLightState() for thing: openwebnet:bus_on_off_switch:4d3a0d8c:45
2019-03-04 09:15:56.673 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *1*0*29##
2019-03-04 09:15:56.674 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  ownId=1.29
2019-03-04 09:15:56.674 [DEBUG] [et.handler.OpenWebNetLightingHandler] - ==OWN:LightingHandler== updateLightState() for thing: openwebnet:bus_on_off_switch:4d3a0d8c:29
2019-03-04 09:15:56.771 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *1*0*64##
2019-03-04 09:15:56.772 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  ownId=1.64
2019-03-04 09:15:56.773 [DEBUG] [et.handler.OpenWebNetLightingHandler] - ==OWN:LightingHandler== updateLightState() for thing: openwebnet:bus_on_off_switch:4d3a0d8c:64
2019-03-04 09:15:56.823 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *1*0*26##
2019-03-04 09:15:56.824 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  ownId=1.26
2019-03-04 09:15:56.824 [DEBUG] [et.handler.OpenWebNetLightingHandler] - ==OWN:LightingHandler== updateLightState() for thing: openwebnet:bus_on_off_switch:4d3a0d8c:26
2019-03-04 09:15:56.892 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *1*0*65##
2019-03-04 09:15:56.893 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  ownId=1.65
2019-03-04 09:15:56.893 [DEBUG] [et.handler.OpenWebNetLightingHandler] - ==OWN:LightingHandler== updateLightState() for thing: openwebnet:bus_on_off_switch:4d3a0d8c:65
2019-03-04 09:15:56.936 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *1*0*24##
2019-03-04 09:15:56.937 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  ownId=1.24
2019-03-04 09:15:56.938 [DEBUG] [et.handler.OpenWebNetLightingHandler] - ==OWN:LightingHandler== updateLightState() for thing: openwebnet:bus_on_off_switch:4d3a0d8c:24
2019-03-04 09:15:57.018 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *1*0*55##
2019-03-04 09:15:57.019 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  ownId=1.55
2019-03-04 09:15:57.019 [DEBUG] [et.handler.OpenWebNetLightingHandler] - ==OWN:LightingHandler== updateLightState() for thing: openwebnet:bus_on_off_switch:4d3a0d8c:55
2019-03-04 09:15:57.098 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *1*1*36##
2019-03-04 09:15:57.099 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  ownId=1.36
2019-03-04 09:15:57.099 [DEBUG] [et.handler.OpenWebNetLightingHandler] - ==OWN:LightingHandler== updateLightState() for thing: openwebnet:bus_on_off_switch:4d3a0d8c:36
2019-03-04 09:15:57.104 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *1*0*18##
2019-03-04 09:15:57.105 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  ownId=1.18
2019-03-04 09:15:57.105 [DEBUG] [et.handler.OpenWebNetLightingHandler] - ==OWN:LightingHandler== updateLightState() for thing: openwebnet:bus_on_off_switch:4d3a0d8c:18
2019-03-04 09:15:57.163 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *1*0*32##
2019-03-04 09:15:57.164 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  ownId=1.32
2019-03-04 09:15:57.164 [DEBUG] [et.handler.OpenWebNetLightingHandler] - ==OWN:LightingHandler== updateLightState() for thing: openwebnet:bus_on_off_switch:4d3a0d8c:32
2019-03-04 09:15:57.212 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *1*0*19##
2019-03-04 09:15:57.212 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  ownId=1.19
2019-03-04 09:15:57.213 [DEBUG] [et.handler.OpenWebNetLightingHandler] - ==OWN:LightingHandler== updateLightState() for thing: openwebnet:bus_on_off_switch:4d3a0d8c:19
2019-03-04 09:15:57.261 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *1*0*46##
2019-03-04 09:15:57.261 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  ownId=1.46
2019-03-04 09:15:57.262 [DEBUG] [et.handler.OpenWebNetLightingHandler] - ==OWN:LightingHandler== updateLightState() for thing: openwebnet:bus_on_off_switch:4d3a0d8c:46
2019-03-04 09:15:57.302 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *1*0*56##
2019-03-04 09:15:57.303 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  ownId=1.56
2019-03-04 09:15:57.303 [DEBUG] [et.handler.OpenWebNetLightingHandler] - ==OWN:LightingHandler== updateLightState() for thing: openwebnet:bus_on_off_switch:4d3a0d8c:56
2019-03-04 09:15:57.334 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *1*0*47##
2019-03-04 09:15:57.335 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  ownId=1.47
2019-03-04 09:15:57.335 [DEBUG] [et.handler.OpenWebNetLightingHandler] - ==OWN:LightingHandler== updateLightState() for thing: openwebnet:bus_on_off_switch:4d3a0d8c:47
2019-03-04 09:15:57.407 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *1*0*57##
2019-03-04 09:15:57.408 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  ownId=1.57
2019-03-04 09:15:57.408 [DEBUG] [et.handler.OpenWebNetLightingHandler] - ==OWN:LightingHandler== updateLightState() for thing: openwebnet:bus_on_off_switch:4d3a0d8c:57
2019-03-04 09:15:57.436 [DEBUG] [g.rfxcom.handler.RFXComBridgeHandler] - Message received: Raw data = 115A011A837202000007EC0000733059B469, Packet type = ENERGY, Seq number = 26, Sub type = ELEC2, Device Id = 33650, Count = 2, Instant Amps = 8.817391304347826, Total Amp Hours = 37566.66154775036, Signal level = 6, Battery level = 9, Instant Power = 2028.0, Total Usage = 8640332.155982582
2019-03-04 09:15:57.437 [DEBUG] [binding.rfxcom.handler.RFXComHandler] - Received message from bridge: rfxcom:bridge:dc4c223e message: Raw data = 115A011A837202000007EC0000733059B469, Packet type = ENERGY, Seq number = 26, Sub type = ELEC2, Device Id = 33650, Count = 2, Instant Amps = 8.817391304347826, Total Amp Hours = 37566.66154775036, Signal level = 6, Battery level = 9, Instant Power = 2028.0, Total Usage = 8640332.155982582
2019-03-04 09:15:57.508 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *1*0*48##
2019-03-04 09:15:57.509 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  ownId=1.48
2019-03-04 09:15:57.509 [DEBUG] [et.handler.OpenWebNetLightingHandler] - ==OWN:LightingHandler== updateLightState() for thing: openwebnet:bus_on_off_switch:4d3a0d8c:48
2019-03-04 09:15:57.533 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *1*0*49##
2019-03-04 09:15:57.534 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  ownId=1.49
2019-03-04 09:15:57.534 [DEBUG] [et.handler.OpenWebNetLightingHandler] - ==OWN:LightingHandler== updateLightState() for thing: openwebnet:bus_on_off_switch:4d3a0d8c:49
2019-03-04 09:15:57.587 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *1*0*59##
2019-03-04 09:15:57.588 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  ownId=1.59
2019-03-04 09:15:57.588 [DEBUG] [et.handler.OpenWebNetLightingHandler] - ==OWN:LightingHandler== updateLightState() for thing: openwebnet:bus_on_off_switch:4d3a0d8c:59
2019-03-04 09:15:57.642 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *1*0*53##
2019-03-04 09:15:57.643 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  ownId=1.53
2019-03-04 09:15:57.644 [DEBUG] [et.handler.OpenWebNetLightingHandler] - ==OWN:LightingHandler== updateLightState() for thing: openwebnet:bus_on_off_switch:4d3a0d8c:53
2019-03-04 09:15:57.813 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *#1001*31*7*111111111111111111111111##
2019-03-04 09:15:57.814 [DEBUG] [penwebnet.message.OpenMessageFactory] - ##openwebnet## WHO=AUTOMATION_DIAGNOSTIC NOT SUPPORTED, frame=*#1001*31*7*111111111111111111111111##
2019-03-04 09:15:57.814 [DEBUG] [org.openwebnet.OpenGatewayBus       ] - ##gw-openwebnet-BUS## Malformed/Unsupported OpenMessage: *#1001*31*7*111111111111111111111111##
2019-03-04 09:15:57.822 [INFO ] [g.openwebnet.bus.MyHomeSocketFactory] - COM RECEIVED  <<<<<<<<<<<<============ *#1001*31*7*111111111111111111111111##
2019-03-04 09:15:57.884 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *#1001*01*7*111111111111111111111111##
2019-03-04 09:15:57.884 [DEBUG] [penwebnet.message.OpenMessageFactory] - ##openwebnet## WHO=AUTOMATION_DIAGNOSTIC NOT SUPPORTED, frame=*#1001*01*7*111111111111111111111111##
2019-03-04 09:15:57.885 [DEBUG] [org.openwebnet.OpenGatewayBus       ] - ##gw-openwebnet-BUS## Malformed/Unsupported OpenMessage: *#1001*01*7*111111111111111111111111##
2019-03-04 09:15:57.890 [INFO ] [g.openwebnet.bus.MyHomeSocketFactory] - COM RECEIVED  <<<<<<<<<<<<============ *#1001*01*7*111111111111111111111111##
2019-03-04 09:15:57.939 [INFO ] [g.openwebnet.bus.MyHomeSocketFactory] - COM RECEIVED  <<<<<<<<<<<<============ *#1001*0*7*111111111111111111111111##
2019-03-04 09:15:57.941 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *#1001*0*7*111111111111111111111111##
2019-03-04 09:15:57.942 [DEBUG] [penwebnet.message.OpenMessageFactory] - ##openwebnet## WHO=AUTOMATION_DIAGNOSTIC NOT SUPPORTED, frame=*#1001*0*7*111111111111111111111111##
2019-03-04 09:15:57.942 [DEBUG] [org.openwebnet.OpenGatewayBus       ] - ##gw-openwebnet-BUS## Malformed/Unsupported OpenMessage: *#1001*0*7*111111111111111111111111##
2019-03-04 09:16:00.874 [INFO ] [g.openwebnet.bus.MyHomeSocketFactory] - COM RECEIVED  <<<<<<<<<<<<============ *#*1##
2019-03-04 09:16:01.175 [DEBUG] [penwebnet.message.OpenMessageFactory] - ##openwebnet## WHO=AUTOMATION_DIAGNOSTIC NOT SUPPORTED, frame=*#1001*31*7*111111111111111111111111##
2019-03-04 09:16:01.175 [INFO ] [org.openwebnet.OpenGatewayBus       ] - ##gw-openwebnet-BUS## IGNORING RESPONSE Malformed/Unsupported OpenMessage: *#1001*31*7*111111111111111111111111##. 
2019-03-04 09:16:01.176 [DEBUG] [penwebnet.message.OpenMessageFactory] - ##openwebnet## WHO=AUTOMATION_DIAGNOSTIC NOT SUPPORTED, frame=*#1001*01*7*111111111111111111111111##
2019-03-04 09:16:01.177 [INFO ] [org.openwebnet.OpenGatewayBus       ] - ##gw-openwebnet-BUS## IGNORING RESPONSE Malformed/Unsupported OpenMessage: *#1001*01*7*111111111111111111111111##. 
2019-03-04 09:16:01.177 [DEBUG] [penwebnet.message.OpenMessageFactory] - ##openwebnet## WHO=AUTOMATION_DIAGNOSTIC NOT SUPPORTED, frame=*#1001*0*7*111111111111111111111111##
2019-03-04 09:16:01.178 [INFO ] [org.openwebnet.OpenGatewayBus       ] - ##gw-openwebnet-BUS## IGNORING RESPONSE Malformed/Unsupported OpenMessage: *#1001*0*7*111111111111111111111111##. 
2019-03-04 09:16:01.179 [INFO ] [g.openwebnet.bus.MyHomeJavaConnector] - COM SENDING   ============>>>>>>>>>>>> *#1018*0*7##
2019-03-04 09:16:01.332 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *#1001*31*7*111111111111111111111111##
2019-03-04 09:16:01.333 [DEBUG] [penwebnet.message.OpenMessageFactory] - ##openwebnet## WHO=AUTOMATION_DIAGNOSTIC NOT SUPPORTED, frame=*#1001*31*7*111111111111111111111111##
2019-03-04 09:16:01.333 [DEBUG] [org.openwebnet.OpenGatewayBus       ] - ##gw-openwebnet-BUS## Malformed/Unsupported OpenMessage: *#1001*31*7*111111111111111111111111##
2019-03-04 09:16:01.333 [INFO ] [g.openwebnet.bus.MyHomeSocketFactory] - COM RECEIVED  <<<<<<<<<<<<============ *#1001*31*7*111111111111111111111111##
2019-03-04 09:16:01.378 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *#1001*01*7*111111111111111111111111##
2019-03-04 09:16:01.378 [DEBUG] [penwebnet.message.OpenMessageFactory] - ##openwebnet## WHO=AUTOMATION_DIAGNOSTIC NOT SUPPORTED, frame=*#1001*01*7*111111111111111111111111##
2019-03-04 09:16:01.378 [DEBUG] [org.openwebnet.OpenGatewayBus       ] - ##gw-openwebnet-BUS## Malformed/Unsupported OpenMessage: *#1001*01*7*111111111111111111111111##
2019-03-04 09:16:01.389 [INFO ] [g.openwebnet.bus.MyHomeSocketFactory] - COM RECEIVED  <<<<<<<<<<<<============ *#1001*01*7*111111111111111111111111##
2019-03-04 09:16:01.440 [INFO ] [g.openwebnet.bus.MyHomeSocketFactory] - COM RECEIVED  <<<<<<<<<<<<============ *#1001*0*7*111111111111111111111111##
2019-03-04 09:16:01.442 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *#1001*0*7*111111111111111111111111##
2019-03-04 09:16:01.443 [DEBUG] [penwebnet.message.OpenMessageFactory] - ##openwebnet## WHO=AUTOMATION_DIAGNOSTIC NOT SUPPORTED, frame=*#1001*0*7*111111111111111111111111##
2019-03-04 09:16:01.443 [DEBUG] [org.openwebnet.OpenGatewayBus       ] - ##gw-openwebnet-BUS## Malformed/Unsupported OpenMessage: *#1001*0*7*111111111111111111111111##
2019-03-04 09:16:04.339 [INFO ] [g.openwebnet.bus.MyHomeSocketFactory] - COM RECEIVED  <<<<<<<<<<<<============ *#*1##
2019-03-04 09:16:04.640 [DEBUG] [penwebnet.message.OpenMessageFactory] - ##openwebnet## WHO=AUTOMATION_DIAGNOSTIC NOT SUPPORTED, frame=*#1001*31*7*111111111111111111111111##
2019-03-04 09:16:04.640 [INFO ] [org.openwebnet.OpenGatewayBus       ] - ##gw-openwebnet-BUS## IGNORING RESPONSE Malformed/Unsupported OpenMessage: *#1001*31*7*111111111111111111111111##. 
2019-03-04 09:16:04.641 [DEBUG] [penwebnet.message.OpenMessageFactory] - ##openwebnet## WHO=AUTOMATION_DIAGNOSTIC NOT SUPPORTED, frame=*#1001*01*7*111111111111111111111111##
2019-03-04 09:16:04.641 [INFO ] [org.openwebnet.OpenGatewayBus       ] - ##gw-openwebnet-BUS## IGNORING RESPONSE Malformed/Unsupported OpenMessage: *#1001*01*7*111111111111111111111111##. 
2019-03-04 09:16:04.642 [DEBUG] [penwebnet.message.OpenMessageFactory] - ##openwebnet## WHO=AUTOMATION_DIAGNOSTIC NOT SUPPORTED, frame=*#1001*0*7*111111111111111111111111##
2019-03-04 09:16:04.642 [INFO ] [org.openwebnet.OpenGatewayBus       ] - ##gw-openwebnet-BUS## IGNORING RESPONSE Malformed/Unsupported OpenMessage: *#1001*0*7*111111111111111111111111##. 
2019-03-04 09:16:04.643 [INFO ] [g.openwebnet.bus.MyHomeJavaConnector] - COM SENDING   ============>>>>>>>>>>>> *#25*30##
2019-03-04 09:16:05.652 [INFO ] [g.openwebnet.bus.MyHomeSocketFactory] - COM RECEIVED  <<<<<<<<<<<<============ *#*1##
2019-03-04 09:16:05.953 [DEBUG] [org.openwebnet.OpenGatewayBus       ] - ##gw-openwebnet-BUS## -----discoverDevices() completed
2019-03-04 09:16:05.954 [INFO ] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN== -------- FINISHED active search for devices on gateway 'BUS Gateway'
2019-03-04 09:16:05.966 [INFO ] [overy.BusGatewayDiscoveryParticipant] - ================================================
2019-03-04 09:16:05.967 [INFO ] [overy.BusGatewayDiscoveryParticipant] - ==OWN:UPnP== DISCOVERED DEVICE: (RemoteDevice) Identity: (RemoteDeviceIdentity) UDN: uuid:RINCON_000E58A050F201400, Descriptor: http://192.168.0.2:1400/xml/device_description.xml, Root: true
2019-03-04 09:16:05.968 [INFO ] [overy.BusGatewayDiscoveryParticipant] - =FRIENDLY NAME: 192.168.0.2 - Sonos Connect
2019-03-04 09:16:05.968 [INFO ] [overy.BusGatewayDiscoveryParticipant] - =BASE URL     : null
2019-03-04 09:16:05.969 [INFO ] [overy.BusGatewayDiscoveryParticipant] - =SERIAL #     : null
2019-03-04 09:16:05.969 [INFO ] [overy.BusGatewayDiscoveryParticipant] - =UPC          : null
2019-03-04 09:16:05.969 [INFO ] [overy.BusGatewayDiscoveryParticipant] - =ID.DESC URL  : http://192.168.0.2:1400/xml/device_description.xml
2019-03-04 09:16:05.970 [INFO ] [overy.BusGatewayDiscoveryParticipant] - =ID.MAX AGE   : 1800
2019-03-04 09:16:05.970 [INFO ] [overy.BusGatewayDiscoveryParticipant] - =MANUFACTURER : Sonos, Inc.
2019-03-04 09:16:05.971 [INFO ] [overy.BusGatewayDiscoveryParticipant] - =MODEL        : Sonos Connect | Sonos Connect | ZP90
2019-03-04 09:16:05.971 [INFO ] [overy.BusGatewayDiscoveryParticipant] - ================================================
2019-03-04 09:16:05.971 [INFO ] [overy.BusGatewayDiscoveryParticipant] - ==OWN:UPnP== DISCOVERED DEVICE: (RemoteDevice) Identity: (RemoteDeviceIdentity) UDN: uuid:b52f6275-4f78-9d21-ffff-ffff802cd83d, Descriptor: http://192.168.0.195:60000/upnp/dev/b52f6275-4f78-9d21-ffff-ffff802cd83d/desc, Root: true
2019-03-04 09:16:05.972 [INFO ] [overy.BusGatewayDiscoveryParticipant] - =FRIENDLY NAME: Julian's Fire TV Stick
2019-03-04 09:16:05.972 [INFO ] [overy.BusGatewayDiscoveryParticipant] - =BASE URL     : null
2019-03-04 09:16:05.973 [INFO ] [overy.BusGatewayDiscoveryParticipant] - =SERIAL #     : null
2019-03-04 09:16:05.973 [INFO ] [overy.BusGatewayDiscoveryParticipant] - =UPC          : null
2019-03-04 09:16:05.973 [INFO ] [overy.BusGatewayDiscoveryParticipant] - =ID.DESC URL  : http://192.168.0.195:60000/upnp/dev/b52f6275-4f78-9d21-ffff-ffff802cd83d/desc
2019-03-04 09:16:05.974 [INFO ] [overy.BusGatewayDiscoveryParticipant] - =ID.MAX AGE   : 1800
2019-03-04 09:16:05.974 [INFO ] [overy.BusGatewayDiscoveryParticipant] - =MANUFACTURER : Amazon
2019-03-04 09:16:05.974 [INFO ] [overy.BusGatewayDiscoveryParticipant] - =MODEL        : AFTT | null | null
2019-03-04 09:16:05.975 [INFO ] [overy.BusGatewayDiscoveryParticipant] - ================================================
2019-03-04 09:16:05.975 [INFO ] [overy.BusGatewayDiscoveryParticipant] - ==OWN:UPnP== DISCOVERED DEVICE: (RemoteDevice) Identity: (RemoteDeviceIdentity) UDN: uuid:pnp-webserver-1_0-00:03:50:86:02:C6, Descriptor: http://192.168.0.35:49153/, Root: true
2019-03-04 09:16:05.976 [INFO ] [overy.BusGatewayDiscoveryParticipant] - =FRIENDLY NAME: 003598
2019-03-04 09:16:05.976 [INFO ] [overy.BusGatewayDiscoveryParticipant] - =BASE URL     : null
2019-03-04 09:16:05.976 [INFO ] [overy.BusGatewayDiscoveryParticipant] - =SERIAL #     : 00:03:50:86:02:C6
2019-03-04 09:16:05.977 [INFO ] [overy.BusGatewayDiscoveryParticipant] - =UPC          : null
2019-03-04 09:16:05.977 [INFO ] [overy.BusGatewayDiscoveryParticipant] - =ID.DESC URL  : http://192.168.0.35:49153/
2019-03-04 09:16:05.977 [INFO ] [overy.BusGatewayDiscoveryParticipant] - =ID.MAX AGE   : 500
2019-03-04 09:16:05.978 [INFO ] [overy.BusGatewayDiscoveryParticipant] - =MANUFACTURER : BTicino S.p.A.
2019-03-04 09:16:05.978 [INFO ] [overy.BusGatewayDiscoveryParticipant] - =MODEL        : F454 | Web Server AV | 1.0
2019-03-04 09:16:05.978 [INFO ] [overy.BusGatewayDiscoveryParticipant] - ================================================
2019-03-04 09:16:05.979 [INFO ] [overy.BusGatewayDiscoveryParticipant] - ==OWN:UPnP== DISCOVERED DEVICE: (RemoteDevice) Identity: (RemoteDeviceIdentity) UDN: uuid:76511643-f7f5-4993-9b5c-962ec7d4cdfb, Descriptor: http://192.168.0.1:39095/rootDesc.xml, Root: true
2019-03-04 09:16:05.979 [INFO ] [overy.BusGatewayDiscoveryParticipant] - =FRIENDLY NAME: RT-AC66U
2019-03-04 09:16:05.980 [INFO ] [overy.BusGatewayDiscoveryParticipant] - =BASE URL     : null
2019-03-04 09:16:05.995 [INFO ] [overy.BusGatewayDiscoveryParticipant] - =SERIAL #     : CAIAA2000001
2019-03-04 09:16:05.996 [INFO ] [overy.BusGatewayDiscoveryParticipant] - =UPC          : null
2019-03-04 09:16:05.996 [INFO ] [overy.BusGatewayDiscoveryParticipant] - =ID.DESC URL  : http://192.168.0.1:39095/rootDesc.xml
2019-03-04 09:16:05.997 [INFO ] [overy.BusGatewayDiscoveryParticipant] - =ID.MAX AGE   : 120
2019-03-04 09:16:05.997 [INFO ] [overy.BusGatewayDiscoveryParticipant] - =MANUFACTURER : ASUSTek
2019-03-04 09:16:05.998 [INFO ] [overy.BusGatewayDiscoveryParticipant] - =MODEL        : RT-AC66U | ASUS Wireless Router | 380.70
2019-03-04 09:16:05.998 [INFO ] [overy.BusGatewayDiscoveryParticipant] - ================================================
2019-03-04 09:16:05.999 [INFO ] [overy.BusGatewayDiscoveryParticipant] - ==OWN:UPnP== DISCOVERED DEVICE: (RemoteDevice) Identity: (RemoteDeviceIdentity) UDN: uuid:b7af08d8-2e03-e5d3-9951-63ca4ee7250e, Descriptor: http://192.168.0.16:32469/DeviceDescription.xml, Root: true
2019-03-04 09:16:05.999 [INFO ] [overy.BusGatewayDiscoveryParticipant] - =FRIENDLY NAME: Plex Media Server: Media Server
2019-03-04 09:16:06.000 [INFO ] [overy.BusGatewayDiscoveryParticipant] - =BASE URL     : null
2019-03-04 09:16:06.000 [INFO ] [overy.BusGatewayDiscoveryParticipant] - =SERIAL #     : null
2019-03-04 09:16:06.001 [INFO ] [overy.BusGatewayDiscoveryParticipant] - =UPC          : null
2019-03-04 09:16:06.001 [INFO ] [overy.BusGatewayDiscoveryParticipant] - =ID.DESC URL  : http://192.168.0.16:32469/DeviceDescription.xml
2019-03-04 09:16:06.002 [INFO ] [overy.BusGatewayDiscoveryParticipant] - =ID.MAX AGE   : 1800
2019-03-04 09:16:06.002 [INFO ] [overy.BusGatewayDiscoveryParticipant] - =MANUFACTURER : Plex, Inc.
2019-03-04 09:16:06.003 [INFO ] [overy.BusGatewayDiscoveryParticipant] - =MODEL        : Plex Media Server | Plex Media Server | 1.14.0.5470
2019-03-04 09:16:06.003 [DEBUG] [ery.OpenWebNetBridgeDiscoveryService] - ==OWN:BridgeDiscovery== getSupportedThingTypes()
2019-03-04 09:16:06.004 [INFO ] [ery.OpenWebNetBridgeDiscoveryService] - ==OWN:BridgeDiscovery== ------ startScan() - SEARCHING for bridges...
2019-03-04 09:16:06.005 [DEBUG] [ery.OpenWebNetBridgeDiscoveryService] - ==OWN:BridgeDiscovery:Dongle== Gateway NULL, creating a new one ...
2019-03-04 09:16:06.006 [DEBUG] [ery.OpenWebNetBridgeDiscoveryService] - ==OWN:BridgeDiscovery:Dongle== ... trying to connect dongle ...
2019-03-04 09:16:06.007 [DEBUG] [org.openwebnet.OpenGatewayZigBee    ] - ##gw-openwebnet## Finding serial ports...
2019-03-04 09:16:06.017 [DEBUG] [org.openwebnet.OpenGatewayZigBee    ] - ##gw-openwebnet## ... found serial port /dev/ttyUSB0
2019-03-04 09:16:06.018 [DEBUG] [org.openwebnet.OpenGatewayZigBee    ] - ##gw-openwebnet## ... found serial port /dev/ttyS0
2019-03-04 09:16:06.018 [DEBUG] [org.openwebnet.OpenGatewayZigBee    ] - ##gw-openwebnet## ... trying to connect USB dongle on serial port /dev/ttyUSB0 ...
2019-03-04 09:16:08.067 [INFO ] [overy.BusGatewayDiscoveryParticipant] - ================================================
2019-03-04 09:16:08.068 [INFO ] [overy.BusGatewayDiscoveryParticipant] - ==OWN:UPnP== DISCOVERED DEVICE: (RemoteDevice) Identity: (RemoteDeviceIdentity) UDN: uuid:b52f6275-4f78-9d21-ffff-ffff802cd83d, Descriptor: http://192.168.0.195:60000/upnp/dev/b52f6275-4f78-9d21-ffff-ffff802cd83d/desc, Root: true
2019-03-04 09:16:08.068 [INFO ] [overy.BusGatewayDiscoveryParticipant] - =FRIENDLY NAME: Julian's Fire TV Stick
2019-03-04 09:16:08.068 [INFO ] [overy.BusGatewayDiscoveryParticipant] - =BASE URL     : null
2019-03-04 09:16:08.068 [INFO ] [overy.BusGatewayDiscoveryParticipant] - =SERIAL #     : null
2019-03-04 09:16:08.068 [INFO ] [overy.BusGatewayDiscoveryParticipant] - =UPC          : null
2019-03-04 09:16:08.068 [INFO ] [overy.BusGatewayDiscoveryParticipant] - =ID.DESC URL  : http://192.168.0.195:60000/upnp/dev/b52f6275-4f78-9d21-ffff-ffff802cd83d/desc
2019-03-04 09:16:08.069 [INFO ] [overy.BusGatewayDiscoveryParticipant] - =ID.MAX AGE   : 1800
2019-03-04 09:16:08.069 [INFO ] [overy.BusGatewayDiscoveryParticipant] - =MANUFACTURER : Amazon
2019-03-04 09:16:08.069 [INFO ] [overy.BusGatewayDiscoveryParticipant] - =MODEL        : AFTT | null | null
2019-03-04 09:16:08.260 [INFO ] [overy.BusGatewayDiscoveryParticipant] - ================================================
2019-03-04 09:16:08.260 [INFO ] [overy.BusGatewayDiscoveryParticipant] - ==OWN:UPnP== DISCOVERED DEVICE: (RemoteDevice) Identity: (RemoteDeviceIdentity) UDN: uuid:b52f6275-4f78-9d21-ffff-ffff802cd83d, Descriptor: http://192.168.0.195:60000/upnp/dev/b52f6275-4f78-9d21-ffff-ffff802cd83d/desc, Root: true
2019-03-04 09:16:08.260 [INFO ] [overy.BusGatewayDiscoveryParticipant] - =FRIENDLY NAME: Julian's Fire TV Stick
2019-03-04 09:16:08.260 [INFO ] [overy.BusGatewayDiscoveryParticipant] - =BASE URL     : null
2019-03-04 09:16:08.260 [INFO ] [overy.BusGatewayDiscoveryParticipant] - =SERIAL #     : null
2019-03-04 09:16:08.261 [INFO ] [overy.BusGatewayDiscoveryParticipant] - =UPC          : null
2019-03-04 09:16:08.261 [INFO ] [overy.BusGatewayDiscoveryParticipant] - =ID.DESC URL  : http://192.168.0.195:60000/upnp/dev/b52f6275-4f78-9d21-ffff-ffff802cd83d/desc
2019-03-04 09:16:08.261 [INFO ] [overy.BusGatewayDiscoveryParticipant] - =ID.MAX AGE   : 1800
2019-03-04 09:16:08.261 [INFO ] [overy.BusGatewayDiscoveryParticipant] - =MANUFACTURER : Amazon
2019-03-04 09:16:08.261 [INFO ] [overy.BusGatewayDiscoveryParticipant] - =MODEL        : AFTT | null | null
2019-03-04 09:16:08.425 [INFO ] [overy.BusGatewayDiscoveryParticipant] - ================================================
2019-03-04 09:16:08.425 [INFO ] [overy.BusGatewayDiscoveryParticipant] - ==OWN:UPnP== DISCOVERED DEVICE: (RemoteDevice) Identity: (RemoteDeviceIdentity) UDN: uuid:b52f6275-4f78-9d21-ffff-ffff802cd83d, Descriptor: http://192.168.0.195:60000/upnp/dev/b52f6275-4f78-9d21-ffff-ffff802cd83d/desc, Root: true
2019-03-04 09:16:08.426 [INFO ] [overy.BusGatewayDiscoveryParticipant] - =FRIENDLY NAME: Julian's Fire TV Stick
2019-03-04 09:16:08.426 [INFO ] [overy.BusGatewayDiscoveryParticipant] - =BASE URL     : null
2019-03-04 09:16:08.426 [INFO ] [overy.BusGatewayDiscoveryParticipant] - =SERIAL #     : null
2019-03-04 09:16:08.426 [INFO ] [overy.BusGatewayDiscoveryParticipant] - =UPC          : null
2019-03-04 09:16:08.426 [INFO ] [overy.BusGatewayDiscoveryParticipant] - =ID.DESC URL  : http://192.168.0.195:60000/upnp/dev/b52f6275-4f78-9d21-ffff-ffff802cd83d/desc
2019-03-04 09:16:08.426 [INFO ] [overy.BusGatewayDiscoveryParticipant] - =ID.MAX AGE   : 1800
2019-03-04 09:16:08.426 [INFO ] [overy.BusGatewayDiscoveryParticipant] - =MANUFACTURER : Amazon
2019-03-04 09:16:08.426 [INFO ] [overy.BusGatewayDiscoveryParticipant] - =MODEL        : AFTT | null | null
2019-03-04 09:16:08.990 [INFO ] [overy.BusGatewayDiscoveryParticipant] - ================================================
2019-03-04 09:16:08.991 [INFO ] [overy.BusGatewayDiscoveryParticipant] - ==OWN:UPnP== DISCOVERED DEVICE: (RemoteDevice) Identity: (RemoteDeviceIdentity) UDN: uuid:b52f6275-4f78-9d21-ffff-ffff802cd83d, Descriptor: http://192.168.0.195:60000/upnp/dev/b52f6275-4f78-9d21-ffff-ffff802cd83d/desc, Root: true
2019-03-04 09:16:08.991 [INFO ] [overy.BusGatewayDiscoveryParticipant] - =FRIENDLY NAME: Julian's Fire TV Stick
2019-03-04 09:16:08.991 [INFO ] [overy.BusGatewayDiscoveryParticipant] - =BASE URL     : null
2019-03-04 09:16:08.991 [INFO ] [overy.BusGatewayDiscoveryParticipant] - =SERIAL #     : null
2019-03-04 09:16:08.991 [INFO ] [overy.BusGatewayDiscoveryParticipant] - =UPC          : null
2019-03-04 09:16:08.991 [INFO ] [overy.BusGatewayDiscoveryParticipant] - =ID.DESC URL  : http://192.168.0.195:60000/upnp/dev/b52f6275-4f78-9d21-ffff-ffff802cd83d/desc
2019-03-04 09:16:08.991 [INFO ] [overy.BusGatewayDiscoveryParticipant] - =ID.MAX AGE   : 1800
2019-03-04 09:16:08.992 [INFO ] [overy.BusGatewayDiscoveryParticipant] - =MANUFACTURER : Amazon
2019-03-04 09:16:08.992 [INFO ] [overy.BusGatewayDiscoveryParticipant] - =MODEL        : AFTT | null | null
2019-03-04 09:16:21.837 [DEBUG] [nternal.connector.RFXComStreamReader] - Received exception, will report it to listeners
org.openhab.binding.rfxcom.internal.exceptions.RFXComTimeoutException: Timeout during packet read
    at org.openhab.binding.rfxcom.internal.connector.RFXComStreamReader.processMessage(RFXComStreamReader.java:87) ~[232:org.openhab.binding.rfxcom:2.4.0]
    at org.openhab.binding.rfxcom.internal.connector.RFXComStreamReader.run(RFXComStreamReader.java:61) [232:org.openhab.binding.rfxcom:2.4.0]
2019-03-04 09:16:21.838 [ERROR] [g.rfxcom.handler.RFXComBridgeHandler] - Error occurred: Timeout during packet read
2019-03-04 09:16:21.839 [DEBUG] [binding.rfxcom.handler.RFXComHandler] - bridgeStatusChanged OFFLINE (COMMUNICATION_ERROR) for thing rfxcom:rfy:7a9aa23d
2019-03-04 09:16:21.840 [DEBUG] [binding.rfxcom.handler.RFXComHandler] - initializeBridge OFFLINE for thing rfxcom:rfy:7a9aa23d
2019-03-04 09:16:21.840 [DEBUG] [binding.rfxcom.handler.RFXComHandler] - bridgeStatusChanged OFFLINE (COMMUNICATION_ERROR) for thing rfxcom:rfy:7b380611
2019-03-04 09:16:21.841 [DEBUG] [binding.rfxcom.handler.RFXComHandler] - bridgeStatusChanged OFFLINE (COMMUNICATION_ERROR) for thing rfxcom:rfy:3731f96b
2019-03-04 09:16:21.841 [DEBUG] [binding.rfxcom.handler.RFXComHandler] - initializeBridge OFFLINE for thing rfxcom:rfy:3731f96b
2019-03-04 09:16:21.841 [DEBUG] [binding.rfxcom.handler.RFXComHandler] - bridgeStatusChanged OFFLINE (COMMUNICATION_ERROR) for thing rfxcom:energy:dc4c223e:33650
2019-03-04 09:16:21.841 [DEBUG] [binding.rfxcom.handler.RFXComHandler] - bridgeStatusChanged OFFLINE (COMMUNICATION_ERROR) for thing rfxcom:rfy:668accfd
2019-03-04 09:16:21.842 [DEBUG] [binding.rfxcom.handler.RFXComHandler] - initializeBridge OFFLINE for thing rfxcom:energy:dc4c223e:33650
2019-03-04 09:16:21.842 [DEBUG] [binding.rfxcom.handler.RFXComHandler] - bridgeStatusChanged OFFLINE (COMMUNICATION_ERROR) for thing rfxcom:lighting4:dc4c223e:282689
2019-03-04 09:16:21.840 [DEBUG] [nternal.connector.RFXComStreamReader] - Data listener stopped
2019-03-04 09:16:21.842 [DEBUG] [binding.rfxcom.handler.RFXComHandler] - bridgeStatusChanged OFFLINE (COMMUNICATION_ERROR) for thing rfxcom:rfy:eb87d19f
2019-03-04 09:16:21.846 [DEBUG] [binding.rfxcom.handler.RFXComHandler] - initializeBridge OFFLINE for thing rfxcom:lighting4:dc4c223e:282689
2019-03-04 09:16:21.846 [DEBUG] [binding.rfxcom.handler.RFXComHandler] - initializeBridge OFFLINE for thing rfxcom:rfy:eb87d19f
2019-03-04 09:16:21.847 [DEBUG] [binding.rfxcom.handler.RFXComHandler] - bridgeStatusChanged OFFLINE (COMMUNICATION_ERROR) for thing rfxcom:rfy:ba2e8dcc
2019-03-04 09:16:21.847 [DEBUG] [binding.rfxcom.handler.RFXComHandler] - initializeBridge OFFLINE for thing rfxcom:rfy:ba2e8dcc
2019-03-04 09:16:21.847 [DEBUG] [binding.rfxcom.handler.RFXComHandler] - bridgeStatusChanged OFFLINE (COMMUNICATION_ERROR) for thing rfxcom:rfy:bedcbd74
2019-03-04 09:16:21.848 [DEBUG] [binding.rfxcom.handler.RFXComHandler] - bridgeStatusChanged OFFLINE (COMMUNICATION_ERROR) for thing rfxcom:rfy:258ba52c
2019-03-04 09:16:21.848 [DEBUG] [binding.rfxcom.handler.RFXComHandler] - initializeBridge OFFLINE for thing rfxcom:rfy:258ba52c
2019-03-04 09:16:21.846 [DEBUG] [binding.rfxcom.handler.RFXComHandler] - initializeBridge OFFLINE for thing rfxcom:rfy:7b380611
2019-03-04 09:16:21.848 [DEBUG] [binding.rfxcom.handler.RFXComHandler] - initializeBridge OFFLINE for thing rfxcom:rfy:bedcbd74
2019-03-04 09:16:21.846 [DEBUG] [binding.rfxcom.handler.RFXComHandler] - bridgeStatusChanged OFFLINE (COMMUNICATION_ERROR) for thing rfxcom:rfy:3440db12
2019-03-04 09:16:21.849 [DEBUG] [binding.rfxcom.handler.RFXComHandler] - initializeBridge OFFLINE for thing rfxcom:rfy:3440db12
2019-03-04 09:16:21.849 [DEBUG] [binding.rfxcom.handler.RFXComHandler] - bridgeStatusChanged OFFLINE (COMMUNICATION_ERROR) for thing rfxcom:lighting4:dc4c223e:282641
2019-03-04 09:16:21.849 [DEBUG] [binding.rfxcom.handler.RFXComHandler] - bridgeStatusChanged OFFLINE (COMMUNICATION_ERROR) for thing rfxcom:rfy:8d130e31
2019-03-04 09:16:21.850 [DEBUG] [binding.rfxcom.handler.RFXComHandler] - initializeBridge OFFLINE for thing rfxcom:lighting4:dc4c223e:282641
2019-03-04 09:16:21.850 [DEBUG] [binding.rfxcom.handler.RFXComHandler] - initializeBridge OFFLINE for thing rfxcom:rfy:8d130e31
2019-03-04 09:16:21.842 [DEBUG] [binding.rfxcom.handler.RFXComHandler] - initializeBridge OFFLINE for thing rfxcom:rfy:668accfd
2019-03-04 09:16:21.944 [DEBUG] [et.internal.OpenWebNetHandlerFactory] - ==OWN:HandlerFactory== createHandler()
2019-03-04 09:16:21.944 [DEBUG] [et.internal.OpenWebNetHandlerFactory] - ==OWN:HandlerFactory== creating NEW LIGHTING Handler
2019-03-04 09:16:21.945 [DEBUG] [et.handler.OpenWebNetLightingHandler] - ==OWN:LightingHandler== constructor
2019-03-04 09:16:21.949 [DEBUG] [ebnet.handler.OpenWebNetThingHandler] - ==OWN:ThingHandler== initialize() thing=openwebnet:bus_on_off_switch:9a40816a
2019-03-04 09:16:21.949 [INFO ] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN:BridgeHandler== registered device ownId=1.8, thing=openwebnet:bus_on_off_switch:9a40816a
2019-03-04 09:16:21.949 [DEBUG] [ebnet.handler.OpenWebNetThingHandler] - ==OWN:ThingHandler== associated thing to bridge with ownId=1.8
2019-03-04 09:16:21.950 [DEBUG] [et.handler.OpenWebNetLightingHandler] - ==OWN:LightingHandler== initialize() thing=openwebnet:bus_on_off_switch:9a40816a
2019-03-04 09:16:29.356 [INFO ] [smarthome.model.script.startup.rules] - Calculating time of day...
2019-03-04 09:16:29.360 [INFO ] [.eclipse.smarthome.model.script.test] - day_start 2019-03-04T00:00:00.000+01:00
2019-03-04 09:16:29.361 [INFO ] [.eclipse.smarthome.model.script.test] - dawn_start 2019-03-04T06:37:00.000+01:00
2019-03-04 09:16:29.362 [INFO ] [.eclipse.smarthome.model.script.test] - morning start 2019-03-04T07:06:00.000+01:00
2019-03-04 09:16:29.363 [INFO ] [.eclipse.smarthome.model.script.test] - afternoon start 2019-03-04T12:45:00.000+01:00
2019-03-04 09:16:29.365 [INFO ] [.eclipse.smarthome.model.script.test] - evening start 2019-03-04T18:22:00.000+01:00
2019-03-04 09:16:29.365 [INFO ] [.eclipse.smarthome.model.script.test] - night start 2019-03-04T19:26:00.000+01:00
2019-03-04 09:16:29.366 [INFO ] [.eclipse.smarthome.model.script.test] - latenight_start 2019-03-04T20:00:00.000+01:00
2019-03-04 09:16:29.367 [INFO ] [.eclipse.smarthome.model.script.test] - day_end 2019-03-05T00:00:00.000+01:00
2019-03-04 09:16:29.368 [INFO ] [eclipse.smarthome.model.script.test1] - Now is 2019-03-04T09:16:29.368+01:00
2019-03-04 09:16:29.369 [INFO ] [eclipse.smarthome.model.script.test1] - Now_5 is 2019-03-04T09:16:34.359+01:00
2019-03-04 09:16:29.370 [INFO ] [smarthome.model.script.startup.rules] - Calculated time of day is MORNING
2019-03-04 09:16:30.234 [WARN ] [io.openhabcloud.internal.CloudClient] - Jetty request 22145615 failed: null
2019-03-04 09:16:30.235 [WARN ] [io.openhabcloud.internal.CloudClient] - Response Failure: null
2019-03-04 09:16:30.235 [WARN ] [io.openhabcloud.internal.CloudClient] - Jetty request 22145826 failed: null
2019-03-04 09:16:30.236 [WARN ] [io.openhabcloud.internal.CloudClient] - Response Failure: null
2019-03-04 09:16:30.246 [WARN ] [io.openhabcloud.internal.CloudClient] - Jetty request 22145815 failed: null
2019-03-04 09:16:30.246 [WARN ] [io.openhabcloud.internal.CloudClient] - Response Failure: null
2019-03-04 09:16:42.248 [DEBUG] [ebnet.handler.OpenWebNetThingHandler] - ==OWN:ThingHandler== handleCommand() (command=OFF - channel=openwebnet:bus_on_off_switch:4d3a0d8c:52:switch)
2019-03-04 09:16:42.249 [DEBUG] [et.handler.OpenWebNetLightingHandler] - ==OWN:LightingHandler== handleSwitchCommand() (command=OFF - channel=openwebnet:bus_on_off_switch:4d3a0d8c:52:switch)
2019-03-04 09:16:42.249 [DEBUG] [et.handler.OpenWebNetLightingHandler] - ==OWN:LightingHandler== toWhere(ChannelUID) ownId=1.52
2019-03-04 09:16:42.249 [INFO ] [g.openwebnet.bus.MyHomeJavaConnector] - COM SENDING   ============>>>>>>>>>>>> *1*0*52##
2019-03-04 09:16:42.250 [DEBUG] [g.openwebnet.bus.MyHomeSocketFactory] - ##gw-openwebnet-socket## CANNOT read from socket: read returned -1
2019-03-04 09:16:42.252 [DEBUG] [g.openwebnet.bus.MyHomeJavaConnector] - ##gw-openwebnet-connector## read returned -1
2019-03-04 09:16:42.252 [DEBUG] [g.openwebnet.bus.MyHomeJavaConnector] - ##gw-openwebnet-connector## let's try with a NEW SESSION...
2019-03-04 09:16:42.252 [INFO ] [g.openwebnet.bus.MyHomeSocketFactory] - ##gw-openwebnet-socket## Trying openCommandSession() socket....  (192.168.0.35:20000)
2019-03-04 09:16:42.253 [DEBUG] [org.openwebnet.bus.MyHomeConnection ] - ##gw-openwebnet-conn## ... starting HANDSHAKE ... 
2019-03-04 09:16:42.254 [DEBUG] [org.openwebnet.bus.MyHomeConnection ] - COM RECEIVED   <<<<<<====== *#*1##
2019-03-04 09:16:42.255 [DEBUG] [org.openwebnet.bus.MyHomeConnection ] - COM SENDING    ======>>>>>> *99*0##
2019-03-04 09:16:42.256 [DEBUG] [org.openwebnet.bus.MyHomeConnection ] - COM RECEIVED   <<<<<<====== *#*1##
2019-03-04 09:16:42.257 [INFO ] [g.openwebnet.bus.MyHomeSocketFactory] - ##gw-openwebnet-socket## ... CONNECTED command session 192.168.0.35:20000
2019-03-04 09:16:42.257 [INFO ] [g.openwebnet.bus.MyHomeJavaConnector] - COM SENDING   ============>>>>>>>>>>>> *1*0*52##
2019-03-04 09:16:42.299 [INFO ] [g.openwebnet.bus.MyHomeSocketFactory] - COM RECEIVED  <<<<<<<<<<<<============ *#*1##
2019-03-04 09:16:42.358 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *1*0*52##
2019-03-04 09:16:42.358 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  ownId=1.52
2019-03-04 09:16:42.359 [DEBUG] [et.handler.OpenWebNetLightingHandler] - ==OWN:LightingHandler== updateLightState() for thing: openwebnet:bus_on_off_switch:4d3a0d8c:52
2019-03-04 09:16:42.770 [DEBUG] [ebnet.handler.OpenWebNetThingHandler] - ==OWN:ThingHandler== handleCommand() (command=REFRESH - channel=openwebnet:bus_on_off_switch:9a40816a:switch)
2019-03-04 09:16:42.770 [DEBUG] [ebnet.handler.OpenWebNetThingHandler] - ==OWN:ThingHandler== Refreshing channel openwebnet:bus_on_off_switch:9a40816a:switch
2019-03-04 09:16:42.770 [DEBUG] [et.handler.OpenWebNetLightingHandler] - ==OWN:LightingHandler== requestChannelState() thingUID=openwebnet:bus_on_off_switch:9a40816a channel=switch
2019-03-04 09:16:42.771 [DEBUG] [et.handler.OpenWebNetLightingHandler] - ==OWN:LightingHandler== toWhere(ChannelUID) ownId=1.8
2019-03-04 09:16:42.771 [INFO ] [g.openwebnet.bus.MyHomeJavaConnector] - COM SENDING   ============>>>>>>>>>>>> *#1*8##
2019-03-04 09:16:42.858 [INFO ] [g.openwebnet.bus.MyHomeSocketFactory] - COM RECEIVED  <<<<<<<<<<<<============ *1*0*81##
2019-03-04 09:16:42.860 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *1*0*81##
2019-03-04 09:16:42.860 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  ownId=1.81
2019-03-04 09:16:42.861 [DEBUG] [et.handler.OpenWebNetLightingHandler] - ==OWN:LightingHandler== updateLightState() for thing: openwebnet:bus_on_off_switch:4d3a0d8c:81
2019-03-04 09:16:43.832 [DEBUG] [g.rfxcom.handler.RFXComBridgeHandler] - Checking RFXCOM transceiver connection, thing status = OFFLINE
2019-03-04 09:16:43.833 [DEBUG] [g.rfxcom.handler.RFXComBridgeHandler] - Connecting to RFXCOM transceiver
2019-03-04 09:16:43.833 [DEBUG] [rnal.connector.RFXComSerialConnector] - Disconnecting
2019-03-04 09:16:43.852 [INFO ] [g.openwebnet.bus.MyHomeSocketFactory] - COM RECEIVED  <<<<<<<<<<<<============ *#*1##
2019-03-04 09:16:45.372 [DEBUG] [org.openwebnet.bus.MyHomeConnection ] - ##gw-openwebnet-conn## sending MON keepalive ACK
2019-03-04 09:16:45.372 [DEBUG] [org.openwebnet.bus.MyHomeConnection ] - MON SENDING    ======>>>>>> *#*1##
2019-03-04 09:16:46.834 [DEBUG] [rnal.connector.RFXComSerialConnector] - Serial port event listener stopped
2019-03-04 09:16:46.835 [DEBUG] [rnal.connector.RFXComSerialConnector] - Interrupt serial listener
2019-03-04 09:16:46.835 [DEBUG] [rnal.connector.RFXComSerialConnector] - Close serial out stream
2019-03-04 09:16:46.835 [DEBUG] [rnal.connector.RFXComSerialConnector] - Close serial in stream
2019-03-04 09:16:46.835 [DEBUG] [rnal.connector.RFXComSerialConnector] - Close serial port
2019-03-04 09:16:46.846 [DEBUG] [rnal.connector.RFXComSerialConnector] - Closed
2019-03-04 09:16:48.847 [ERROR] [g.rfxcom.handler.RFXComBridgeHandler] - Connection to RFXCOM transceiver failed
gnu.io.PortInUseException: org.openwebnet.OpenGatewayZigBee
    at gnu.io.CommPortIdentifier.open(CommPortIdentifier.java:459) ~[?:?]
    at org.openhab.binding.rfxcom.internal.connector.RFXComSerialConnector.connect(RFXComSerialConnector.java:48) ~[?:?]
    at org.openhab.binding.rfxcom.handler.RFXComBridgeHandler.connect(RFXComBridgeHandler.java:175) ~[?:?]
    at org.openhab.binding.rfxcom.handler.RFXComBridgeHandler.lambda$0(RFXComBridgeHandler.java:149) ~[?:?]
    at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) [?:?]
    at java.util.concurrent.FutureTask.runAndReset(FutureTask.java:308) [?:?]
    at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$301(ScheduledThreadPoolExecutor.java:180) [?:?]
    at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:294) [?:?]
    at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) [?:?]
    at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) [?:?]
    at java.lang.Thread.run(Thread.java:748) [?:?]
2019-03-04 09:16:49.153 [INFO ] [ebnet.handler.OpenWebNetThingHandler] - ==OWN:ThingHandler== Thing state request timer expired, still unknown. Setting thing=openwebnet:bus_on_off_switch:9a40816a to OFFLINE
2019-03-04 09:16:49.154 [DEBUG] [ebnet.handler.OpenWebNetThingHandler] - ==OWN:ThingHandler== Thing OFFLINE
2019-03-04 09:16:51.224 [DEBUG] [ery.OpenWebNetDeviceDiscoveryService] - ==OWN:DeviceDiscovery== ------ stopScan()
2019-03-04 09:16:51.225 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN== -------- scanIsActive=false
2019-03-04 09:16:57.723 [DEBUG] [ebnet.handler.OpenWebNetThingHandler] - ==OWN:ThingHandler== handleCommand() (command=ON - channel=openwebnet:bus_on_off_switch:4d3a0d8c:81:switch)
2019-03-04 09:16:57.723 [DEBUG] [ebnet.handler.OpenWebNetThingHandler] - ==OWN:ThingHandler== handleCommand() (command=ON - channel=openwebnet:bus_on_off_switch:9a40816a:switch)
2019-03-04 09:16:57.724 [DEBUG] [et.handler.OpenWebNetLightingHandler] - ==OWN:LightingHandler== handleSwitchCommand() (command=ON - channel=openwebnet:bus_on_off_switch:4d3a0d8c:81:switch)
2019-03-04 09:16:57.724 [DEBUG] [et.handler.OpenWebNetLightingHandler] - ==OWN:LightingHandler== handleSwitchCommand() (command=ON - channel=openwebnet:bus_on_off_switch:9a40816a:switch)
2019-03-04 09:16:57.724 [DEBUG] [et.handler.OpenWebNetLightingHandler] - ==OWN:LightingHandler== toWhere(ChannelUID) ownId=1.81
2019-03-04 09:16:57.724 [DEBUG] [et.handler.OpenWebNetLightingHandler] - ==OWN:LightingHandler== toWhere(ChannelUID) ownId=1.8
julesbike commented 5 years ago

Sorry I pressed Close by accident

mvalla commented 5 years ago

I looked at the log, the critical part is:

2019-03-04 09:16:06.018 [DEBUG] [org.openwebnet.OpenGatewayZigBee    ] - ##gw-openwebnet## ... trying to connect USB dongle on serial port /dev/ttyUSB0 ...
[...........]
2019-03-04 09:16:21.837 [DEBUG] [nternal.connector.RFXComStreamReader] - Received exception, will report it to listeners
org.openhab.binding.rfxcom.internal.exceptions.RFXComTimeoutException: Timeout during packet read

openwebnet (after a search is activated) tries to connect to the USB (/dev/ttyUSB0) to which RFXCOM is probably already connected; then RFXCOM returns a RFXComTimeoutException and then fails to connect again later. This is strange: the OWN binding should immediately return a PortInUseException when it tries the port, which does not occur. I will add more log lines so another test can be done.

mvalla commented 5 years ago

@julesbike can you do a test with this binding where I added some log messages? https://github.com/mvalla/openhab2-addons/releases/download/own-2.5.0.M2-1/org.openhab.binding.openwebnet-2.5.0.M2-1.jar

can you send here the new logs?

mvalla commented 5 years ago

@julesbike did you have time to do a test with the jar 2.5.0.M2-1 posted above?

mvalla commented 5 years ago

@julesbike did you have time to test with the new jar version 2.5.0.M2 with has more logs to understand better?

mvalla commented 5 years ago

I will close this issue in 5 days if no more feedback is received

mvalla commented 5 years ago

Since no further feedback has been provided by reporter, I will close this issue for now.