hi @feodor , did you try the new official version (2.5.8)?
I did not. I am wait the support of “Automation” in official version.
Thanks 
As well pointed out by @bastler , if everyone avoids testing and waits for the official binding version to be feature complete, the official version will never be bugs-free!
Regarding your tests:
- Things added via file will never be put in inbox, that’s a normal behaviour in OH, so it’s normal that EGFlur_Li and EGFlur_Dekolampe have not been found in inbox. Also, if they have no item associated, they will only become online when activated from physical switch; instead, if they have item associated when created they will be refreshed automatically for status and therefore become online. This same behaviour of all bindings.
- Management of Dimmer levels100 needs to be added, feel free to open an issue in openhab/openhab-addons Issues · openhab/openhab-addons · GitHub starting with
[openwebnet]in the title
for example when selecting 57 it doesn´t round but jumps to 50, no problem i think.
but when i slide the dimmer to a value below 20, it always jups back to 20;
- that is something that can be improved, by storing the requested level in the thing object. I will open an issue for that.
Hi @m4rk,
what your log shows here is that light 0412 is configured as bus_on_off_switch while it received 100% from BUS, (*1*10*0412##), which is not a valid message for that type. If you change the configuration and make it a dimmer it should work.
Periodic gateway disconnects errors that @cybermcm, @m4rk, @bastler and @1V1rusYx have seen are “normal”: if the binding does not receive any event message from gateway for 2mins then -as a safety measure- disconnects from it and reconnects to be sure gw is still reachable. This also helps to identify quickly a broken connection with gw for example for network issues.
If you do some activity on the BUS this breaks the “silence” from gw and thus the binding does not disconnect.
This can be improved (I will open an issue for that), however is very strange that in your gw no event is sent for 2min, at least the time event should be received…
The disconnect is explained in my last message. Probably MH202 does send more events and therefore no safety disconnect is needed.
What is strange in your TOUCHSCREEN10 case is that during reconnection the AUTH phase fails (at least sometimes). If you send me in PM a debug level log then this happens I will have a look. Probably AUTH timers are too short for TOUCHSCREEN10 and need to be increased.
@massi: thx for your answer. What do you exactly mean with “time event”, there is no such event in my log at all?
I update openHAB to 2.5.9. And install official binding-openwebnet - 2.5.9. But binding does not work.
All my configs in files. I did not change config files.
Bridge openwebnet:zb_gateway:myZBgateway "ZigBee USB Gateway" [serialPort="/dev/legrand_usb"] {
zb_on_off_switch2u myzigbeeswitch_zal "Зал"@"Зал" [ where="7696361"]
zb_on_off_switch2u myzigbeeswitch_zal_bal "Балкон зал"@"Зал" [ where="7690906"]
zb_on_off_switch2u myzigbeeswitch_san1 "Ванна"@"Ванна" [ where="7696377"]
zb_on_off_switch2u myzigbeeswitch_san3 "Ванна внутри"@"Ванна" [ where="7696358"]
zb_on_off_switch2u myzigbeeswitch_san2 "Туалет"@"Ванна" [ where="7696064"]
zb_on_off_switch2u myzigbeeswitch_kitchen "Кухня"@"Кухня" [ where="7696359"]
zb_on_off_switch myzigbeeswitch_kitchen2 "Кухня фартук"@"Кухня" [ where="7705110"]
zb_on_off_switch2u myzigbeeswitch_kitchen_bal "Балкон кухни"@"Кухня" [ where="7696373"]
zb_on_off_switch2u myzigbeeswitch_enter "Вход"@"Коридор" [ where="7696033"]
zb_on_off_switch myzigbeeswitch_enter2 "Гардероб"@"Коридор" [ where="7690224"]
zb_on_off_switch2u myzigbeeswitch_room1 "Детская"@"Детская" [ where="7696066"]
zb_on_off_switch2u myzigbeeswitch_room2 "Спальня"@"Спальня" [ where="7696059"]
zb_on_off_switch myzigbeeswitch_enter3 "Свет в кладовке"@"Коридор" [ where="7692832"]
zb_automation myzigbeeswitch_enter4 "Рольставни в кладовке"@"Коридор" [ where="7706771" , shutterRun="10000"]
}
There are errors in log
2020-09-20 22:40:05.382 [thome.event.ExtensionEvent] - Extension 'binding-openwebnet' has been installed.
2020-09-20 22:40:05.635 [hingStatusInfoChangedEvent] - 'openwebnet:zb_gateway:myZBgateway' changed from UNINITIALIZED (HANDLER_MISSING_ERROR) to INITIALIZING
2020-09-20 22:40:05.678 [hingStatusInfoChangedEvent] - 'openwebnet:zb_gateway:myZBgateway' changed from INITIALIZING to UNKNOWN
2020-09-20 22:40:05.763 [hingStatusInfoChangedEvent] - 'openwebnet:zb_on_off_switch2u:myZBgateway:myzigbeeswitch_zal' changed from UNINITIALIZED (HANDLER_MISSING_ERROR) to INITIALIZING
2020-09-20 22:40:05.770 [hingStatusInfoChangedEvent] - 'openwebnet:zb_on_off_switch2u:myZBgateway:myzigbeeswitch_san2' changed from UNINITIALIZED (HANDLER_MISSING_ERROR) to INITIALIZING
2020-09-20 22:40:05.772 [hingStatusInfoChangedEvent] - 'openwebnet:zb_on_off_switch2u:myZBgateway:myzigbeeswitch_san3' changed from UNINITIALIZED (HANDLER_MISSING_ERROR) to INITIALIZING
2020-09-20 22:40:05.774 [hingStatusInfoChangedEvent] - 'openwebnet:zb_on_off_switch2u:myZBgateway:myzigbeeswitch_san1' changed from UNINITIALIZED (HANDLER_MISSING_ERROR) to INITIALIZING
2020-09-20 22:40:05.783 [hingStatusInfoChangedEvent] - 'openwebnet:zb_on_off_switch2u:myZBgateway:myzigbeeswitch_kitchen' changed from UNINITIALIZED (HANDLER_MISSING_ERROR) to INITIALIZING
==> /var/log/openhab2/openhab.log <==
2020-09-20 22:40:05.801 [ERROR] [nal.common.AbstractInvocationHandler] - An error occurred while calling method 'ThingHandler.initialize()' on 'org.openhab.binding.openwebnet.handler.OpenWebNetLightingHandler@bc2005': WHERE address is invalid
java.lang.IllegalArgumentException: WHERE address is invalid
at org.openwebnet4j.message.WhereZigBee.<init>(WhereZigBee.java:39) ~[?:?]
at org.openhab.binding.openwebnet.handler.OpenWebNetThingHandler.initialize(OpenWebNetThingHandler.java:82) ~[?:?]
at sun.reflect.GeneratedMethodAccessor34.invoke(Unknown Source) ~[?:?]
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:1.8.0_222]
at java.lang.reflect.Method.invoke(Method.java:498) ~[?:1.8.0_222]
at org.eclipse.smarthome.core.internal.common.AbstractInvocationHandler.invokeDirect(AbstractInvocationHandler.java:152) [bundleFile:?]
at org.eclipse.smarthome.core.internal.common.Invocation.call(Invocation.java:52) [bundleFile:?]
at java.util.concurrent.FutureTask.run(FutureTask.java:266) [?:1.8.0_222]
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) [?:1.8.0_222]
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) [?:1.8.0_222]
at java.lang.Thread.run(Thread.java:748) [?:1.8.0_222]
2020-09-20 22:40:05.825 [ERROR] [nal.common.AbstractInvocationHandler] - An error occurred while calling method 'ThingHandler.initialize()' on 'org.openhab.binding.openwebnet.handler.OpenWebNetLightingHandler@16aac19': WHERE address is invalid
java.lang.IllegalArgumentException: WHERE address is invalid
at org.openwebnet4j.message.WhereZigBee.<init>(WhereZigBee.java:39) ~[?:?]
A time event is a msg sent on the BUS by the gw with the date-time info. Maybe not all gw send this, or it depends on specific configuration.
Example:
<<<< *#13**22*21*36*48*002*00*20*09*2020##
(h21:36:48s +2 timezone, 20/9/2020)
Hi @feodor
OH 2.5.9 is yet to be announced by Kai, but you anticipated it. Good!
Where address for USB Gateway devices have changed since the testing version, and also I have to say that switch2u support is still buggy (only first of the 2 switches work). I tell you this because it seems you have few of them in your config.
You have to change them to complete the address with Unit, so for example:
[ where="7705110"] becomes [ where="770511001"] by adding 01 at the end.
Same for Automation.
For switch2u you may try to configure them via file as simple switches but use two different Units.
So for example:
zb_on_off_switch2u myzigbeeswitch_zal "Зал"@"Зал" [ where="7696361"]
becomes:
zb_on_off_switch myzigbeeswitch_zal1 "Зал"@"Зал1" [ where="769636101"]
zb_on_off_switch myzigbeeswitch_zal2 "Зал"@"Зал2" [ where="769636102"]
these only as a temporary workaround. Tell me if it works.
Or you can use the old testing version.
I am distributing a new one with a later expire.
Feel free to open an issue for this in openhab/openhab-addons starting with [openwebnet] in the title.
I count on you for USB Gateway testing: you are one of the few to have this system installed.
I change file
openhabian@openhab:/etc/openhab2/things $ cat openwebnet.things
Bridge openwebnet:zb_gateway:myZBgateway "ZigBee USB Gateway" [ serialPort="/dev/legrand_usb" ] {
zb_on_off_switch2u myzigbeeswitch_zal "Зал"@"Зал" [ where="769636101" ]
zb_on_off_switch2u myzigbeeswitch_zal_bal "Балкон зал"@"Зал" [ where="769090601" ]
zb_on_off_switch2u myzigbeeswitch_san1 "Ванна"@"Ванна" [ where="769637701" ]
zb_on_off_switch2u myzigbeeswitch_san3 "Ванна внутри"@"Ванна" [ where="769635801" ]
zb_on_off_switch2u myzigbeeswitch_san2 "Туалет"@"Ванна" [ where="769606401" ]
zb_on_off_switch2u myzigbeeswitch_kitchen "Кухня"@"Кухня" [ where="769635901" ]
zb_on_off_switch myzigbeeswitch_kitchen2 "Кухня фартук"@"Кухня" [ where="770511001" ]
zb_on_off_switch2u myzigbeeswitch_kitchen_bal "Балкон кухни"@"Кухня" [ where="769637301" ]
zb_on_off_switch2u myzigbeeswitch_enter "Вход"@"Коридор" [ where="769603301" ]
zb_on_off_switch myzigbeeswitch_enter2 "Гардероб"@"Коридор" [ where="769022401" ]
zb_on_off_switch2u myzigbeeswitch_room1 "Детская"@"Детская" [ where="769606601" ]
zb_on_off_switch2u myzigbeeswitch_room2 "Спальня"@"Спальня" [ where="769605901" ]
zb_on_off_switch myzigbeeswitch_enter3 "Свет в кладовке"@"Коридор" [ where="769283201" ]
zb_automation myzigbeeswitch_enter4 "Рольставни в кладовке"@"Коридор" [ where="770677101" , shutterRun="10000" ]
}
But nothing happend
2020-09-20 23:16:13.039 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'openwebnet.things'
==> /var/log/openhab2/events.log <==
2020-09-20 23:16:13.123 [hingStatusInfoChangedEvent] - 'openwebnet:zb_gateway:myZBgateway' changed from UNINITIALIZED to INITIALIZING
2020-09-20 23:16:13.139 [hingStatusInfoChangedEvent] - 'openwebnet:zb_gateway:myZBgateway' changed from INITIALIZING to UNKNOWN
==> /var/log/openhab2/openhab.log <==
2020-09-20 23:16:13.224 [WARN ] [nwebnet4j.communication.USBConnector] - ##USB-conn## Recevied ACK/NACK without a command waiting, skipping it
2020-09-20 23:16:13.238 [WARN ] [nwebnet4j.communication.USBConnector] - ##USB-conn## Recevied ACK/NACK without a command waiting, skipping it
2020-09-20 23:16:13.243 [INFO ] [bnet.handler.OpenWebNetBridgeHandler] - ------------------- CONNECTED to USB (ZigBee) gateway - USB port: /dev/legrand_usb
2020-09-20 23:16:13.248 [INFO ] [bnet.handler.OpenWebNetBridgeHandler] - properties updated for 'openwebnet:zb_gateway:myZBgateway'
==> /var/log/openhab2/events.log <==
2020-09-20 23:16:13.249 [me.event.ThingUpdatedEvent] - Thing 'openwebnet:zb_gateway:myZBgateway' has been updated.
2020-09-20 23:16:13.252 [hingStatusInfoChangedEvent] - 'openwebnet:zb_gateway:myZBgateway' changed from UNKNOWN to ONLINE
2020-09-20 23:16:13.258 [hingStatusInfoChangedEvent] - 'openwebnet:zb_on_off_switch2u:myZBgateway:myzigbeeswitch_zal' changed from UNINITIALIZED to INITIALIZING
==> /var/log/openhab2/openhab.log <==
2020-09-20 23:16:13.272 [ERROR] [nal.common.AbstractInvocationHandler] - An error occurred while calling method 'ThingHandler.initialize()' on 'org.openhab.binding.openwebnet.handler.OpenWebNetLightingHandler@121f0bd': WHERE address is invalid
java.lang.IllegalArgumentException: WHERE address is invalid
at org.openwebnet4j.message.WhereZigBee.<init>(WhereZigBee.java:39) ~[?:?]
at org.openhab.binding.openwebnet.handler.OpenWebNetThingHandler.initialize(OpenWebNetThingHandler.java:82) ~[?:?]
at sun.reflect.GeneratedMethodAccessor35.invoke(Unknown Source) ~[?:?]
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:1.8.0_222]
at java.lang.reflect.Method.invoke(Method.java:498) ~[?:1.8.0_222]
at org.eclipse.smarthome.core.internal.common.AbstractInvocationHandler.invokeDirect(AbstractInvocationHandler.java:152) [bundleFile:?]
at org.eclipse.smarthome.core.internal.common.Invocation.call(Invocation.java:52) [bundleFile:?]
at java.util.concurrent.FutureTask.run(FutureTask.java:266) [?:1.8.0_222]
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) [?:1.8.0_222]
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) [?:1.8.0_222]
at java.lang.Thread.run(Thread.java:748) [?:1.8.0_222]
==> /var/log/openhab2/events.log <==
2020-09-20 23:16:13.288 [hingStatusInfoChangedEvent] - 'openwebnet:zb_on_off_switch2u:myZBgateway:myzigbeeswitch_zal' changed from INITIALIZING to UNINITIALIZED (HANDLER_INITIALIZING_ERROR): WHERE address is invalid
==> /var/log/openhab2/openhab.log <==
2020-09-20 23:16:13.290 [ERROR] [core.thing.internal.ThingManagerImpl] - Exception occurred while initializing handler of thing 'openwebnet:zb_on_off_switch2u:myZBgateway:myzigbeeswitch_zal': WHERE address is invalid
java.lang.IllegalArgumentException: WHERE address is invalid
at org.openwebnet4j.message.WhereZigBee.<init>(WhereZigBee.java:39) ~[?:?]
after a while in the logs appeared
2020-09-20 23:24:36.558 [WARN ] [nwebnet4j.communication.USBConnector] - ##USB-conn## UNSUPPORTED FRAME: *#1000*769283200#9*94##, skipping it
2020-09-20 23:24:37.406 [WARN ] [nwebnet4j.communication.USBConnector] - ##USB-conn## UNSUPPORTED FRAME: *#1000*769090600#9*94##, skipping it
2020-09-20 23:24:38.732 [WARN ] [nwebnet4j.communication.USBConnector] - ##USB-conn## UNSUPPORTED FRAME: *#1000*769022400#9*94##, skipping it
2020-09-20 23:24:39.594 [WARN ] [nwebnet4j.communication.USBConnector] - ##USB-conn## UNSUPPORTED FRAME: *#1000*769606600#9*94##, skipping it
2020-09-20 23:24:46.864 [WARN ] [nwebnet4j.communication.USBConnector] - ##USB-conn## UNSUPPORTED FRAME: *#1000*770511000#9*94##, skipping it
2020-09-20 23:24:47.470 [WARN ] [nwebnet4j.communication.USBConnector] - ##USB-conn## UNSUPPORTED FRAME: *#1000*769637300#9*94##, skipping it
2020-09-20 23:24:55.276 [WARN ] [nwebnet4j.communication.USBConnector] - ##USB-conn## UNSUPPORTED FRAME: *#1000*770677100#9*94##, skipping it
2020-09-20 23:25:02.624 [WARN ] [nwebnet4j.communication.USBConnector] - ##USB-conn## UNSUPPORTED FRAME: *#1000*769603300#9*94##, skipping it
2020-09-20 23:25:04.305 [WARN ] [nwebnet4j.communication.USBConnector] - ##USB-conn## UNSUPPORTED FRAME: *#1000*769637700#9*94##, skipping it
2020-09-20 23:25:05.757 [WARN ] [nwebnet4j.communication.USBConnector] - ##USB-conn## UNSUPPORTED FRAME: *#1000*769606400#9*94##, skipping it
2020-09-20 23:25:08.177 [WARN ] [nwebnet4j.communication.USBConnector] - ##USB-conn## UNSUPPORTED FRAME: *#1000*769635800#9*94##, skipping it
2020-09-20 23:25:08.902 [WARN ] [nwebnet4j.communication.USBConnector] - ##USB-conn## UNSUPPORTED FRAME: *#1000*769635900#9*94##, skipping it
2020-09-20 23:25:10.354 [WARN ] [nwebnet4j.communication.USBConnector] - ##USB-conn## UNSUPPORTED FRAME: *#1000*769605900#9*94##, skipping it
2020-09-20 23:25:15.652 [WARN ] [nwebnet4j.communication.USBConnector] - ##USB-conn## UNSUPPORTED FRAME: *#1000*769636100#9*94##, skipping it
@massi: thx for this info (time events), never saw this in my log. Can’t remeber a config option either but of course still possible that it exists
this is a log where we can see that indeed there is a long time when no action depending bticino-gw is logged. then all things go offline, and immediately come back online:
2020-09-20 20:39:46.920 [vent.ItemStateChangedEvent] - iSchlafzi_Ankleide_Li changed from 100 to 0
(big timegap here when no light was logged)
2020-09-20 21:05:28.519 [hingStatusInfoChangedEvent] - 'openwebnet:bus_gateway:BticinoGateway' changed from ONLINE to OFFLINE (COMMUNICATION_ERROR): Disconnected from gateway (onDisconnected - BUS-MON-Rcv got IOException: Read timed out)
2020-09-20 21:05:28.620 [hingStatusInfoChangedEvent] - 'openwebnet:bus_on_off_switch:BticinoGateway:Sackgasse_Li' changed from ONLINE to OFFLINE (BRIDGE_OFFLINE)
2020-09-20 21:05:28.700 [hingStatusInfoChangedEvent] - 'openwebnet:bus_on_off_switch:BticinoGateway:Hof_Li' changed from ONLINE to OFFLINE (BRIDGE_OFFLINE)
(all tings going offline ...)
2020-09-20 21:05:29.788 [hingStatusInfoChangedEvent] - 'openwebnet:bus_on_off_switch:BticinoGateway:Absperrventil_Ak' changed from ONLINE to OFFLINE (BRIDGE_OFFLINE)
2020-09-20 21:05:29.797 [hingStatusInfoChangedEvent] - 'openwebnet:bus_on_off_switch:BticinoGateway:Klingel_Ak' changed from ONLINE to OFFLINE (BRIDGE_OFFLINE)
2020-09-20 21:05:30.162 [.event.RuleStatusInfoEvent] - 0fabd082-8fba-48e7-af9c-f075f7d63774 updated: RUNNING
2020-09-20 21:05:30.186 [.event.RuleStatusInfoEvent] - c5dbd151-8939-4242-a482-5e308986b0e6 updated: RUNNING
2020-09-20 21:05:30.216 [.event.RuleStatusInfoEvent] - c5dbd151-8939-4242-a482-5e308986b0e6 updated: IDLE
2020-09-20 21:05:30.271 [.event.RuleStatusInfoEvent] - 0fabd082-8fba-48e7-af9c-f075f7d63774 updated: IDLE
2020-09-20 21:05:31.936 [hingStatusInfoChangedEvent] - 'openwebnet:bus_gateway:BticinoGateway' changed from OFFLINE (COMMUNICATION_ERROR): Disconnected from gateway (onDisconnected - BUS-MON-Rcv got IOException: Read timed out) to ONLINE
2020-09-20 21:05:32.125 [hingStatusInfoChangedEvent] - 'openwebnet:bus_on_off_switch:BticinoGateway:Sackgasse_Li' changed from OFFLINE (BRIDGE_OFFLINE) to ONLINE
2020-09-20 21:05:32.282 [hingStatusInfoChangedEvent] - 'openwebnet:bus_on_off_switch:BticinoGateway:Hof_Li' changed from OFFLINE (BRIDGE_OFFLINE) to ONLINE
2020-09-20 21:05:32.380 [hingStatusInfoChangedEvent] - 'openwebnet:bus_on_off_switch:BticinoGateway:Garage_Sd' changed from OFFLINE (BRIDGE_OFFLINE) to ONLINE
2020-09-20 21:05:32.476 [hingStatusInfoChangedEvent] - 'openwebnet:bus_on_off_switch:BticinoGateway:Garage_Holzlager_Li' changed from OFFLINE (BRIDGE_OFFLINE) to ONLINE
i also see no time-event but i think as sonn as more things are supported and more traffic then this going offline should appear less.
I think it should be “fixed”… I’ve only lights and shutters and therefore the traffic between OpenHab and BTicino will remain rare…
Hi Massi,
PaperUI discovery finds all my dimmers as switch type. If I maulay define the thing and items as dimmer in config files instead then it works OK. .
I am using MH202 as gateway but will now switch to screen10 next.
The MH202 is ‘disturbed’ when used as the gateway and after PaperUI discovery is run. Some of my scenarios stop working and only a reboot of the M202 fixes it. I have reported this before and it seems that those scenarios with ‘only if’ conditions are affected. I suspect some of the scenario conditions are left in a undefined state after the scan.
edited to add:
now running screen10 as gw and see frequent, evry 2mins, disconnection and reconnection events which I didn’t see with the MH202 as gw. Normal as you say but would be interesting to know why that the MH202 doesn’t show these… i guess its busy checking for events to trigger the many scenarios I have.
I make clean install OpenHAB 2.5.9
Installed only
feature:install openhab-transport-serial
feature:install openhab-transport-upnp
Added group rule and gave openHAB access to port
sudo usermod -a -G dialout openhab
EXTRA_JAVA_OPTS="-Xms250m -Xmx350m -Dgnu.io.rxtx.SerialPorts=/dev/legrand_usb"
Install new binding-openwebnet - 2.5.9
And added Legrand USB Stick over PaperUI.
2020-09-21 14:27:03.758 [thome.event.ExtensionEvent] - Extension 'binding-openwebnet' has been installed.
2020-09-21 14:27:27.448 [ERROR] [org.openwebnet4j.OpenGateway ] - Error while connecting to Gateway: Could not communicate with a USB ZigBee Gateway on serial port: /dev/legrand_usb. Serial returned: *#*0##
2020-09-21 14:27:27.456 [INFO ] [bnet.handler.OpenWebNetBridgeHandler] - ------------------- ON CONNECTION ERROR: Could not communicate with a USB ZigBee Gateway on serial port: /dev/legrand_usb. Serial returned: *#*0##
2020-09-21 14:27:27.462 [INFO ] [bnet.handler.OpenWebNetBridgeHandler] - ------------------- Starting RECONNECT cycle to gateway
==> /var/log/openhab2/events.log <==
2020-09-21 14:27:27.225 [hingStatusInfoChangedEvent] - 'openwebnet:zb_gateway:292579fe' changed from UNINITIALIZED to INITIALIZING
2020-09-21 14:27:27.336 [hingStatusInfoChangedEvent] - 'openwebnet:zb_gateway:292579fe' changed from INITIALIZING to UNKNOWN
2020-09-21 14:27:27.466 [hingStatusInfoChangedEvent] - 'openwebnet:zb_gateway:292579fe' changed from UNKNOWN to OFFLINE (COMMUNICATION_ERROR): Could not communicate with a USB ZigBee Gateway on serial port: /dev/legrand_usb. Serial returned: *#*0##
After that I delete and install binding again. It status become ONLINE.
2020-09-21 14:42:45.057 [thome.event.ExtensionEvent] - Extension 'binding-openwebnet' has been uninstalled.
2020-09-21 14:46:37.821 [thome.event.ExtensionEvent] - Extension 'binding-openwebnet' has been installed.
2020-09-21 14:46:38.171 [WARN ] [nwebnet4j.communication.USBConnector] - ##USB-conn## Recevied ACK/NACK without a command waiting, skipping it
2020-09-21 14:46:38.189 [WARN ] [nwebnet4j.communication.USBConnector] - ##USB-conn## Recevied ACK/NACK without a command waiting, skipping it
2020-09-21 14:46:38.194 [INFO ] [bnet.handler.OpenWebNetBridgeHandler] - ------------------- CONNECTED to USB (ZigBee) gateway - USB port: /dev/legrand_usb
2020-09-21 14:46:38.203 [INFO ] [bnet.handler.OpenWebNetBridgeHandler] - properties updated for 'openwebnet:zb_gateway:292579fe'
==> /var/log/openhab2/events.log <==
2020-09-21 14:46:37.987 [hingStatusInfoChangedEvent] - 'openwebnet:zb_gateway:292579fe' changed from UNINITIALIZED (HANDLER_MISSING_ERROR) to INITIALIZING
2020-09-21 14:46:38.045 [hingStatusInfoChangedEvent] - 'openwebnet:zb_gateway:292579fe' changed from INITIALIZING to UNKNOWN
2020-09-21 14:46:38.204 [me.event.ThingUpdatedEvent] - Thing 'openwebnet:zb_gateway:292579fe' has been updated.
2020-09-21 14:46:38.210 [hingStatusInfoChangedEvent] - 'openwebnet:zb_gateway:292579fe' changed from UNKNOWN to ONLINE
Try add Zigbee Switch. First try where=“769022401”. Second where=“7690224”. See error in log
2020-09-21 14:48:51.308 [ERROR] [nal.common.AbstractInvocationHandler] - An error occurred while calling method 'ThingHandler.thingUpdated()' on 'org.openhab.binding.openwebnet.handler.OpenWebNetLightingHandler@14cd6df': WHERE address is invalid
java.lang.IllegalArgumentException: WHERE address is invalid
at org.openwebnet4j.message.WhereZigBee.<init>(WhereZigBee.java:39) ~[?:?]
at org.openhab.binding.openwebnet.handler.OpenWebNetThingHandler.initialize(OpenWebNetThingHandler.java:82) ~[?:?]
at org.eclipse.smarthome.core.thing.binding.BaseThingHandler.thingUpdated(BaseThingHandler.java:166) ~[?:?]
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[?:1.8.0_265]
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) ~[?:1.8.0_265]
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:1.8.0_265]
at java.lang.reflect.Method.invoke(Method.java:498) ~[?:1.8.0_265]
at org.eclipse.smarthome.core.internal.common.AbstractInvocationHandler.invokeDirect(AbstractInvocationHandler.java:152) [bundleFile:?]
at org.eclipse.smarthome.core.internal.common.Invocation.call(Invocation.java:52) [bundleFile:?]
at java.util.concurrent.FutureTask.run(FutureTask.java:266) [?:1.8.0_265]
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) [?:1.8.0_265]
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) [?:1.8.0_265]
at java.lang.Thread.run(Thread.java:748) [?:1.8.0_265]
==> /var/log/openhab2/events.log <==
2020-09-21 14:48:51.376 [me.event.ThingUpdatedEvent] - Thing 'openwebnet:zb_on_off_switch:96f9cb9d' has been updated.
==> /var/log/openhab2/openhab.log <==
2020-09-21 14:49:09.807 [ERROR] [st.core.internal.thing.ThingResource] - Exception during HTTP PUT request for update config at 'things/openwebnet:zb_on_off_switch:96f9cb9d/config'
java.lang.IllegalArgumentException: WHERE address is invalid
at org.openwebnet4j.message.WhereZigBee.<init>(WhereZigBee.java:39) ~[?:?]
at org.openhab.binding.openwebnet.handler.OpenWebNetThingHandler.initialize(OpenWebNetThingHandler.java:82) ~[?:?]
at org.eclipse.smarthome.core.thing.binding.BaseThingHandler.handleConfigurationUpdate(BaseThingHandler.java:106) ~[?:?]
at org.eclipse.smarthome.core.thing.internal.ThingRegistryImpl.updateConfiguration(ThingRegistryImpl.java:93) ~[?:?]
at org.eclipse.smarthome.io.rest.core.internal.thing.ThingResource.updateConfiguration(ThingResource.java:438) [bundleFile:?]
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[?:1.8.0_265]
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) ~[?:1.8.0_265]
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:1.8.0_265]
at java.lang.reflect.Method.invoke(Method.java:498) ~[?:1.8.0_265]
at org.glassfish.jersey.server.model.internal.ResourceMethodInvocationHandlerFactory$1.invoke(ResourceMethodInvocationHandlerFactory.java:81) [bundleFile:?]
at org.glassfish.jersey.server.model.internal.AbstractJavaResourceMethodDispatcher$1.run(AbstractJavaResourceMethodDispatcher.java:144) [bundleFile:?]
at org.glassfish.jersey.server.model.internal.AbstractJavaResourceMethodDispatcher.invoke(AbstractJavaResourceMethodDispatcher.java:161) [bundleFile:?]
at org.glassfish.jersey.server.model.internal.JavaResourceMethodDispatcherProvider$ResponseOutInvoker.doDispatch(JavaResourceMethodDispatcherProvider.java:160) [bundleFile:?]
at org.glassfish.jersey.server.model.internal.AbstractJavaResourceMethodDispatcher.dispatch(AbstractJavaResourceMethodDispatcher.java:99) [bundleFile:?]
at org.glassfish.jersey.server.model.ResourceMethodInvoker.invoke(ResourceMethodInvoker.java:389) [bundleFile:?]
at org.glassfish.jersey.server.model.ResourceMethodInvoker.apply(ResourceMethodInvoker.java:347) [bundleFile:?]
at org.glassfish.jersey.server.model.ResourceMethodInvoker.apply(ResourceMethodInvoker.java:102) [bundleFile:?]
at org.glassfish.jersey.server.ServerRuntime$2.run(ServerRuntime.java:326) [bundleFile:?]
at org.glassfish.jersey.internal.Errors$1.call(Errors.java:271) [bundleFile:?]
at org.glassfish.jersey.internal.Errors$1.call(Errors.java:267) [bundleFile:?]
at org.glassfish.jersey.internal.Errors.process(Errors.java:315) [bundleFile:?]
at org.glassfish.jersey.internal.Errors.process(Errors.java:297) [bundleFile:?]
at org.glassfish.jersey.internal.Errors.process(Errors.java:267) [bundleFile:?]
at org.glassfish.jersey.process.internal.RequestScope.runInScope(RequestScope.java:317) [bundleFile:?]
at org.glassfish.jersey.server.ServerRuntime.process(ServerRuntime.java:305) [bundleFile:?]
at org.glassfish.jersey.server.ApplicationHandler.handle(ApplicationHandler.java:1154) [bundleFile:?]
at org.glassfish.jersey.servlet.WebComponent.serviceImpl(WebComponent.java:473) [bundleFile:?]
at org.glassfish.jersey.servlet.WebComponent.service(WebComponent.java:427) [bundleFile:?]
at org.glassfish.jersey.servlet.ServletContainer.service(ServletContainer.java:388) [bundleFile:?]
at org.glassfish.jersey.servlet.ServletContainer.service(ServletContainer.java:341) [bundleFile:?]
at org.glassfish.jersey.servlet.ServletContainer.service(ServletContainer.java:228) [bundleFile:?]
at com.eclipsesource.jaxrs.publisher.internal.ServletContainerBridge.service(ServletContainerBridge.java:76) [bundleFile:?]
at org.eclipse.jetty.servlet.ServletHolder.handle(ServletHolder.java:852) [bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:544) [bundleFile:9.4.20.v20190813]
at org.ops4j.pax.web.service.jetty.internal.HttpServiceServletHandler.doHandle(HttpServiceServletHandler.java:71) [bundleFile:?]
at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:143) [bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.security.SecurityHandler.handle(SecurityHandler.java:536) [bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127) [bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:235) [bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1581) [bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:233) [bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1307) [bundleFile:9.4.20.v20190813]
at org.ops4j.pax.web.service.jetty.internal.HttpServiceContext.doHandle(HttpServiceContext.java:293) [bundleFile:?]
at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:188) [bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:482) [bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1549) [bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:186) [bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1204) [bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141) [bundleFile:9.4.20.v20190813]
at org.ops4j.pax.web.service.jetty.internal.JettyServerHandlerCollection.handle(JettyServerHandlerCollection.java:80) [bundleFile:?]
at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127) [bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.server.Server.handle(Server.java:494) [bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:374) [bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:268) [bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:311) [bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:103) [bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.io.ChannelEndPoint$2.run(ChannelEndPoint.java:117) [bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.runTask(EatWhatYouKill.java:336) [bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:313) [bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.tryProduce(EatWhatYouKill.java:171) [bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.run(EatWhatYouKill.java:129) [bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:367) [bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:782) [bundleFile:9.4.20.v20190813]
at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:918) [bundleFile:9.4.20.v20190813]
at java.lang.Thread.run(Thread.java:748) [?:1.8.0_265]
==> /var/log/openhab2/events.log <==
2020-09-21 14:49:09.807 [me.event.ThingUpdatedEvent] - Thing 'openwebnet:zb_on_off_switch:96f9cb9d' has been updated.
Tryed to uninstall official binding and downloaded [2.5.0.M4 (EXPIRED)]. Everything worked.
before starting discovering, did you set your dimmers ON and dimmed (20-100%) as indicated in the README?
yes I remember this case, but this is the only case I have seen and I cannot think of a solution. What discovery does is just ask status about all lights/shutters using the documented OWN request. If this causes misbehaviour of gateway and its scenarios, it’s a BTicino problem. What you could test is send the same discovery/status request messages via official BTicino client and see if the gw does the same.
Maybe MH202 sends some periodic event msg, before the 2min deadline while screen10 does not. You can see it from logs