[velux] New OpenHAB2 binding - feedback welcome!

funcat · February 16, 2020, 12:22pm

Hi all,
Same thing that @lobermeier has for me.
Here are (some) logs very verbose (every minute for each device) :

here are spamming logs !

2020-02-16 13:09:40.382 [WARN ] [ux.internal.bridge.slip.SCgetProduct] - Gateway response with NodeID 0 unexpected as query asked for NodeID 8.
2020-02-16 13:09:40.383 [INFO ] [.internal.handler.VeluxBridgeHandler] - handleCommandScheduled(velux:actuator:home:klf200_salleDeBain:position,REFRESH): updating of item velux:actuator:home:klf200_salleDeBain:position (type velux:actuator/position) failed.
2020-02-16 13:09:40.886 [WARN ] [ux.internal.bridge.slip.SCgetProduct] - Gateway response with NodeID 7 unexpected as query asked for NodeID 3.
2020-02-16 13:09:40.888 [INFO ] [.internal.handler.VeluxBridgeHandler] - handleCommandScheduled(velux:actuator:home:klf200_bureau:position,REFRESH): updating of item velux:actuator:home:klf200_bureau:position (type velux:actuator/position) failed.
2020-02-16 13:09:41.393 [WARN ] [ux.internal.bridge.slip.SCgetProduct] - Gateway response with NodeID 1 unexpected as query asked for NodeID 3.
2020-02-16 13:09:41.394 [INFO ] [.internal.handler.VeluxBridgeHandler] - handleCommandScheduled(velux:actuator:home:klf200_salleDEau:position,REFRESH): updating of item velux:actuator:home:klf200_salleDEau:position (type velux:actuator/position) failed.
2020-02-16 13:09:41.901 [WARN ] [ux.internal.bridge.slip.SCgetProduct] - Gateway response with NodeID 2 unexpected as query asked for NodeID 11.
2020-02-16 13:09:41.902 [INFO ] [.internal.handler.VeluxBridgeHandler] - handleCommandScheduled(velux:actuator:home:klf200_salonBar:position,REFRESH): updating of item velux:actuator:home:klf200_salonBar:position (type velux:actuator/position) failed.
2020-02-16 13:09:42.411 [WARN ] [ternal.bridge.slip.SCgetDeviceStatus] - Gateway response GW_GET_NODE_INFORMATION_NTF (528) cannot be handled at this point of interaction.
2020-02-16 13:09:42.416 [INFO ] [.internal.handler.VeluxBridgeHandler] - handleCommandScheduled(velux:klf200:home:status,REFRESH): updating of item velux:klf200:home:status (type velux:klf200/status) failed.
2020-02-16 13:09:42.920 [WARN ] [ux.internal.bridge.slip.SCgetProduct] - Gateway response with NodeID 6 unexpected as query asked for NodeID 0.
2020-02-16 13:09:42.922 [INFO ] [.internal.handler.VeluxBridgeHandler] - handleCommandScheduled(velux:actuator:home:klf200_chambreRue:position,REFRESH): updating of item velux:actuator:home:klf200_chambreRue:position (type velux:actuator/position) failed.
2020-02-16 13:09:43.427 [WARN ] [ux.internal.bridge.slip.SCgetProduct] - Gateway response with NodeID 4 unexpected as query asked for NodeID 0.
2020-02-16 13:09:43.428 [INFO ] [.internal.handler.VeluxBridgeHandler] - handleCommandScheduled(velux:actuator:home:klf200_garage:position,REFRESH): updating of item velux:actuator:home:klf200_garage:position (type velux:actuator/position) failed.
2020-02-16 13:09:43.933 [WARN ] [ux.internal.bridge.slip.SCgetProduct] - Gateway response with NodeID 12 unexpected as query asked for NodeID 7.
2020-02-16 13:09:43.934 [INFO ] [.internal.handler.VeluxBridgeHandler] - handleCommandScheduled(velux:actuator:home:klf200_chambreSuite:position,REFRESH): updating of item velux:actuator:home:klf200_chambreSuite:position (type velux:actuator/position) failed.
2020-02-16 13:09:44.445 [WARN ] [ux.internal.bridge.slip.SCgetProduct] - Gateway response with NodeID 5 unexpected as query asked for NodeID 7.
2020-02-16 13:09:44.446 [INFO ] [.internal.handler.VeluxBridgeHandler] - handleCommandScheduled(velux:actuator:home:klf200_cuisineCoin:position,REFRESH): updating of item velux:actuator:home:klf200_cuisineCoin:position (type velux:actuator/position) failed.
2020-02-16 13:09:44.951 [WARN ] [ux.internal.bridge.slip.SCgetProduct] - Gateway response with NodeID 10 unexpected as query asked for NodeID 2.
2020-02-16 13:09:44.952 [INFO ] [.internal.handler.VeluxBridgeHandler] - handleCommandScheduled(velux:actuator:home:klf200_chambreJardin:position,REFRESH): updating of item velux:actuator:home:klf200_chambreJardin:position (type velux:actuator/position) failed.
2020-02-16 13:09:45.461 [WARN ] [ux.internal.bridge.slip.SCgetProduct] - Gateway response with NodeID 9 unexpected as query asked for NodeID 2.
2020-02-16 13:09:45.462 [INFO ] [.internal.handler.VeluxBridgeHandler] - handleCommandScheduled(velux:actuator:home:klf200_salonCoin:position,REFRESH): updating of item velux:actuator:home:klf200_salonCoin:position (type velux:actuator/position) failed.
2020-02-16 13:09:45.968 [WARN ] [ux.internal.bridge.slip.SCgetProduct] - Gateway response with NodeID 8 unexpected as query asked for NodeID 12.
2020-02-16 13:09:45.969 [INFO ] [.internal.handler.VeluxBridgeHandler] - handleCommandScheduled(velux:actuator:home:klf200_cuisineEvier:position,REFRESH): updating of item velux:actuator:home:klf200_cuisineEvier:position (type velux:actuator/position) failed.
2020-02-16 13:09:46.480 [WARN ] [ux.internal.bridge.slip.SCgetProduct] - Gateway response with NodeID 3 unexpected as query asked for NodeID 12.
2020-02-16 13:09:46.481 [INFO ] [.internal.handler.VeluxBridgeHandler] - handleCommandScheduled(velux:actuator:home:klf200_escalier:position,REFRESH): updating of item velux:actuator:home:klf200_escalier:position (type velux:actuator/position) failed.
2020-02-16 13:10:39.894 [WARN ] [ux.internal.bridge.slip.SCgetProduct] - Gateway response with NodeID 11 unexpected as query asked for NodeID 4.
2020-02-16 13:10:39.897 [INFO ] [.internal.handler.VeluxBridgeHandler] - handleCommandScheduled(velux:actuator:home:klf200_salleAManger:position,REFRESH): updating of item velux:actuator:home:klf200_salleAManger:position (type velux:actuator/position) failed.
2020-02-16 13:10:40.402 [WARN ] [ux.internal.bridge.slip.SCgetProduct] - Gateway response with NodeID 10 unexpected as query asked for NodeID 4.
2020-02-16 13:10:40.403 [INFO ] [.internal.handler.VeluxBridgeHandler] - handleCommandScheduled(velux:actuator:home:klf200_chambreJardin:position,REFRESH): updating of item velux:actuator:home:klf200_chambreJardin:position (type velux:actuator/position) failed.
2020-02-16 13:10:40.906 [WARN ] [ux.internal.bridge.slip.SCgetProduct] - Gateway response with NodeID 5 unexpected as query asked for NodeID 6.
2020-02-16 13:10:40.908 [INFO ] [.internal.handler.VeluxBridgeHandler] - handleCommandScheduled(velux:actuator:home:klf200_cuisineCoin:position,REFRESH): updating of item velux:actuator:home:klf200_cuisineCoin:position (type velux:actuator/position) failed.
2020-02-16 13:10:41.417 [WARN ] [ux.internal.bridge.slip.SCgetProduct] - Gateway response with NodeID 0 unexpected as query asked for NodeID 6.
2020-02-16 13:10:41.418 [INFO ] [.internal.handler.VeluxBridgeHandler] - handleCommandScheduled(velux:actuator:home:klf200_salleDeBain:position,REFRESH): updating of item velux:actuator:home:klf200_salleDeBain:position (type velux:actuator/position) failed.
2020-02-16 13:10:41.924 [WARN ] [ux.internal.bridge.slip.SCgetProduct] - Gateway response with NodeID 7 unexpected as query asked for NodeID 3.
2020-02-16 13:10:41.925 [INFO ] [.internal.handler.VeluxBridgeHandler] - handleCommandScheduled(velux:actuator:home:klf200_bureau:position,REFRESH): updating of item velux:actuator:home:klf200_bureau:position (type velux:actuator/position) failed.
2020-02-16 13:10:42.434 [WARN ] [ux.internal.bridge.slip.SCgetProduct] - Gateway response with NodeID 2 unexpected as query asked for NodeID 3.
2020-02-16 13:10:42.436 [INFO ] [.internal.handler.VeluxBridgeHandler] - handleCommandScheduled(velux:actuator:home:klf200_salonBar:position,REFRESH): updating of item velux:actuator:home:klf200_salonBar:position (type velux:actuator/position) failed.
2020-02-16 13:10:42.939 [WARN ] [ternal.bridge.slip.SCgetDeviceStatus] - Gateway response GW_GET_NODE_INFORMATION_CFM (513) cannot be handled at this point of interaction.
2020-02-16 13:10:42.940 [INFO ] [.internal.handler.VeluxBridgeHandler] - handleCommandScheduled(velux:klf200:home:status,REFRESH): updating of item velux:klf200:home:status (type velux:klf200/status) failed.
2020-02-16 13:10:43.447 [WARN ] [ux.internal.bridge.slip.SCgetProduct] - Gateway response with NodeID 3 unexpected as query asked for NodeID 8.
2020-02-16 13:10:43.449 [INFO ] [.internal.handler.VeluxBridgeHandler] - handleCommandScheduled(velux:actuator:home:klf200_escalier:position,REFRESH): updating of item velux:actuator:home:klf200_escalier:position (type velux:actuator/position) failed.
2020-02-16 13:10:43.953 [WARN ] [ux.internal.bridge.slip.SCgetProduct] - Gateway response GW_GET_STATE_CFM (13) cannot be handled at this point of interaction.
2020-02-16 13:10:43.955 [WARN ] [ux.internal.bridge.slip.SCgetProduct] - Gateway response with NodeID 8 unexpected as query asked for NodeID 9.
2020-02-16 13:10:43.956 [INFO ] [.internal.handler.VeluxBridgeHandler] - handleCommandScheduled(velux:actuator:home:klf200_cuisineEvier:position,REFRESH): updating of item velux:actuator:home:klf200_cuisineEvier:position (type velux:actuator/position) failed.
2020-02-16 13:10:44.465 [WARN ] [ux.internal.bridge.slip.SCgetProduct] - Gateway response with NodeID 6 unexpected as query asked for NodeID 9.
2020-02-16 13:10:44.466 [INFO ] [.internal.handler.VeluxBridgeHandler] - handleCommandScheduled(velux:actuator:home:klf200_chambreRue:position,REFRESH): updating of item velux:actuator:home:klf200_chambreRue:position (type velux:actuator/position) failed.
2020-02-16 13:10:44.970 [WARN ] [ux.internal.bridge.slip.SCgetProduct] - Gateway response with NodeID 4 unexpected as query asked for NodeID 1.
2020-02-16 13:10:44.971 [INFO ] [.internal.handler.VeluxBridgeHandler] - handleCommandScheduled(velux:actuator:home:klf200_garage:position,REFRESH): updating of item velux:actuator:home:klf200_garage:position (type velux:actuator/position) failed.
2020-02-16 13:10:45.480 [WARN ] [ux.internal.bridge.slip.SCgetProduct] - Gateway response with NodeID 12 unexpected as query asked for NodeID 1.
2020-02-16 13:10:45.485 [INFO ] [.internal.handler.VeluxBridgeHandler] - handleCommandScheduled(velux:actuator:home:klf200_chambreSuite:position,REFRESH): updating of item velux:actuator:home:klf200_chambreSuite:position (type velux:actuator/position) failed.
2020-02-16 13:10:45.989 [WARN ] [ux.internal.bridge.slip.SCgetProduct] - Gateway response with NodeID 1 unexpected as query asked for NodeID 5.
2020-02-16 13:10:45.990 [INFO ] [.internal.handler.VeluxBridgeHandler] - handleCommandScheduled(velux:actuator:home:klf200_salleDEau:position,REFRESH): updating of item velux:actuator:home:klf200_salleDEau:position (type velux:actuator/position) failed.
2020-02-16 13:10:46.499 [WARN ] [ux.internal.bridge.slip.SCgetProduct] - Gateway response with NodeID 9 unexpected as query asked for NodeID 5.
2020-02-16 13:10:46.500 [INFO ] [.internal.handler.VeluxBridgeHandler] - handleCommandScheduled(velux:actuator:home:klf200_salonCoin:position,REFRESH): updating of item velux:actuator:home:klf200_salonCoin:position (type velux:actuator/position) failed.

…and so on …

Although the plugin was working well for months this bug appears twice in a week.
It disappears when i manualy do a “bundle:restart” in the console.

Johnny-b · February 16, 2020, 2:56pm

Today i started also with strange behavior of Openhab.
I am running on Openhab 2.5 and binding org.openhab.binding.velux-2.5.1.202001051139
all of the sudden it looks like that my somfy shutter that always has being reported with a serial of zeros is not valid anymore within the things configuration of paper ui, where a serialnumber is required but in the past i always used the name instead of it.
i get the following msg in the log,

Product "Keuken" / SLIDER_SHUTTER (bridgeIndex=1,serial=Keuken,position=0000)
2020-02-16 15:31:28.341 [INFO ] [.internal.handler.VeluxBridgeHandler] - velux Bridge is online with 16 scenes and 4 actuators, now.
2020-02-16 15:31:39.778 [INFO ] [.internal.handler.VeluxBridgeHandler] - handleCommandScheduled(velux:rollershutter:home:Keuken:position,REFRESH): updating of item velux:rollershutter:home:Keuken:position (type velux:rollershutter/position) failed

but i cant get a hold of it !
any help is welcome

lobermeier · February 16, 2020, 3:23pm

Hi Funcat,

thats exact what i had and it dissapeared when I extended the timout value from 500 to 2000mSec.
In the docuementation the value shoule be default 1000 but in my case it was 500.

Seems that the KLF200 is quite lazy in answering and maybe therefore messages get out of sync. As i am using a PLC conncection btw. the KLF and my network I temporary replaced that by a direct connection, blaiming the devolo causing delays, but that didn’t solve the problem, the parameter did.

br Leo

EjvindHald · February 16, 2020, 9:07pm

Hi @gs4711 or @Kim_Andersen
I am experimenting with detecting the behaviour of the rain sensor. Can any of you provide a working example of a Thing or Item file with limitationMinimum or limitationMaximum inside?

Thanks.

Kim_Andersen · February 16, 2020, 10:17pm

I cant cause I dont use these options.

funcat · February 17, 2020, 10:10am

Hi @lobermeier
Setup was already on 2000ms and also on wired network (1gbps).
I will give a try with 4000ms but i doubt this is a solution, it seems a veeeeryyyyy loooong timeout.
I’ll keep you in touch.

gs4711 · February 17, 2020, 4:27pm

Could you please share the protocol log (level INFO) with activated “protocol logging”?

funcat · February 17, 2020, 5:20pm

Hi @gs4711
I tried to activate :

isProtocolTraceEnabled=true

(and not “isProtocolTraceEnabled=yes” as said in the doc )
But this is very verbose and i had to stop it. Here some logs :

Summary

2020-02-17 18:10:24.051 [INFO ] [.internal.handler.VeluxBridgeHandler] - Initializing Velux Bridge 'velux:klf200:home'.
2020-02-17 18:10:24.054 [INFO ] [.binding.velux.internal.VeluxBinding] - veluxConfig[protocol=slip,ipAddress=192.168.0.23,tcpPort=51200,password=**********,timeoutMsecs=3000,retries=6,refreshMsecs=10000,isBulkRetrievalEnabled=true,isSequentialEnforced=false,isProtocolTraceEnabled=true]
2020-02-17 18:10:24.090 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Sending command GW_PASSWORD_ENTER_REQ.
2020-02-17 18:10:24.092 [INFO ] [nternal.bridge.slip.io.SSLconnection] - Starting velux bridge connection.
2020-02-17 18:10:31.483 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Received answer GW_PASSWORD_ENTER_CFM.
2020-02-17 18:10:31.484 [INFO ] [g.velux.internal.bridge.slip.SClogin] - velux bridge connection successfully established (login succeeded).
2020-02-17 18:10:31.489 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Sending command GW_GET_STATE_REQ.
2020-02-17 18:10:34.495 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Received answer GW_GET_STATE_CFM.
2020-02-17 18:10:34.499 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Sending command GW_GET_SCENE_LIST_REQ.
2020-02-17 18:10:37.502 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Received answer GW_GET_SCENE_LIST_CFM.
2020-02-17 18:10:37.509 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Received answer GW_GET_SCENE_LIST_NTF.
2020-02-17 18:10:37.519 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Received answer GW_GET_SCENE_LIST_NTF.
2020-02-17 18:10:37.528 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Received answer GW_GET_SCENE_LIST_NTF.
2020-02-17 18:10:37.537 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Received answer GW_GET_SCENE_LIST_NTF.
2020-02-17 18:10:37.546 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Received answer GW_GET_SCENE_LIST_NTF.
2020-02-17 18:10:37.555 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Received answer GW_GET_SCENE_LIST_NTF.
2020-02-17 18:10:37.564 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Received answer GW_GET_SCENE_LIST_NTF.
2020-02-17 18:10:37.573 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Received answer GW_GET_SCENE_LIST_NTF.
2020-02-17 18:10:37.583 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Received answer GW_GET_SCENE_LIST_NTF.
2020-02-17 18:10:37.592 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Received answer GW_GET_SCENE_LIST_NTF.
2020-02-17 18:10:37.599 [INFO ] [.internal.handler.VeluxBridgeHandler] - Found velux scenes:
2020-02-17 18:10:37.604 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Sending command GW_GET_ALL_NODES_INFORMATION_REQ.
2020-02-17 18:10:40.606 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Received answer GW_GET_ALL_NODES_INFORMATION_CFM.
2020-02-17 18:10:40.609 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Received answer GW_GET_ALL_NODES_INFORMATION_NTF.
2020-02-17 18:10:40.612 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Received answer GW_GET_ALL_NODES_INFORMATION_NTF.
2020-02-17 18:10:40.615 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Received answer GW_GET_ALL_NODES_INFORMATION_NTF.
2020-02-17 18:10:40.619 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Received answer GW_GET_ALL_NODES_INFORMATION_NTF.
2020-02-17 18:10:40.622 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Received answer GW_GET_ALL_NODES_INFORMATION_NTF.
2020-02-17 18:10:40.626 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Received answer GW_GET_ALL_NODES_INFORMATION_NTF.
2020-02-17 18:10:40.629 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Received answer GW_GET_ALL_NODES_INFORMATION_NTF.
2020-02-17 18:10:40.632 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Received answer GW_GET_ALL_NODES_INFORMATION_NTF.
2020-02-17 18:10:40.635 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Received answer GW_GET_ALL_NODES_INFORMATION_NTF.
2020-02-17 18:10:40.639 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Received answer GW_GET_ALL_NODES_INFORMATION_NTF.
2020-02-17 18:10:40.642 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Received answer GW_GET_ALL_NODES_INFORMATION_NTF.
2020-02-17 18:10:40.645 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Received answer GW_GET_ALL_NODES_INFORMATION_NTF.
2020-02-17 18:10:40.649 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Received answer GW_GET_ALL_NODES_INFORMATION_NTF.
2020-02-17 18:10:40.651 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Received answer GW_GET_ALL_NODES_INFORMATION_FINISHED_NTF.
2020-02-17 18:10:40.652 [INFO ] [.internal.handler.VeluxBridgeHandler] - Found velux actuators:
2020-02-17 18:10:40.655 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Sending command GW_HOUSE_STATUS_MONITOR_ENABLE_REQ.
2020-02-17 18:10:43.657 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Received answer GW_HOUSE_STATUS_MONITOR_ENABLE_CFM.
2020-02-17 18:10:43.658 [INFO ] [.internal.handler.VeluxBridgeHandler] - velux Bridge is online with 30 scenes and 13 actuators, now.
2020-02-17 18:10:53.679 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Sending command GW_GET_NODE_INFORMATION_REQ.
2020-02-17 18:10:56.688 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Received answer GW_GET_NODE_INFORMATION_CFM.
2020-02-17 18:10:56.694 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Received answer GW_GET_NODE_INFORMATION_NTF.
2020-02-17 18:10:56.706 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Sending command GW_GET_NODE_INFORMATION_REQ.
2020-02-17 18:10:59.709 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Received answer GW_GET_NODE_INFORMATION_CFM.
2020-02-17 18:10:59.715 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Received answer GW_GET_NODE_INFORMATION_NTF.
2020-02-17 18:10:59.724 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Sending command GW_GET_NODE_INFORMATION_REQ.
2020-02-17 18:11:02.726 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Received answer GW_GET_NODE_INFORMATION_CFM.
2020-02-17 18:11:02.729 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Received answer GW_GET_NODE_INFORMATION_NTF.
2020-02-17 18:11:02.734 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Sending command GW_GET_NODE_INFORMATION_REQ.
2020-02-17 18:11:05.737 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Received answer GW_GET_NODE_INFORMATION_CFM.
2020-02-17 18:11:05.743 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Received answer GW_GET_NODE_INFORMATION_NTF.
2020-02-17 18:11:05.752 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Sending command GW_GET_STATE_REQ.
2020-02-17 18:11:08.755 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Received answer GW_GET_STATE_CFM.
2020-02-17 18:11:08.759 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Sending command GW_GET_NODE_INFORMATION_REQ.
2020-02-17 18:11:11.762 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Received answer GW_GET_NODE_INFORMATION_CFM.
2020-02-17 18:11:11.768 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Received answer GW_GET_NODE_INFORMATION_NTF.
2020-02-17 18:11:11.780 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Sending command GW_GET_NODE_INFORMATION_REQ.
2020-02-17 18:11:14.782 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Received answer GW_GET_NODE_INFORMATION_CFM.
2020-02-17 18:11:14.788 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Received answer GW_GET_NODE_INFORMATION_NTF.
2020-02-17 18:11:14.798 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Sending command GW_GET_NODE_INFORMATION_REQ.
2020-02-17 18:11:17.802 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Received answer GW_GET_NODE_INFORMATION_CFM.
2020-02-17 18:11:17.808 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Received answer GW_GET_NODE_INFORMATION_NTF.
2020-02-17 18:11:17.817 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Sending command GW_GET_NODE_INFORMATION_REQ.
2020-02-17 18:11:20.819 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Received answer GW_GET_NODE_INFORMATION_CFM.
2020-02-17 18:11:20.822 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Received answer GW_GET_NODE_INFORMATION_NTF.
2020-02-17 18:11:20.847 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Sending command GW_GET_NODE_INFORMATION_REQ.
2020-02-17 18:11:23.850 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Received answer GW_GET_NODE_INFORMATION_CFM.
2020-02-17 18:11:23.856 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Received answer GW_GET_NODE_INFORMATION_NTF.
2020-02-17 18:11:23.869 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Sending command GW_GET_NODE_INFORMATION_REQ.
2020-02-17 18:11:26.873 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Received answer GW_GET_NODE_INFORMATION_CFM.
2020-02-17 18:11:26.878 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Received answer GW_GET_NODE_INFORMATION_NTF.
2020-02-17 18:11:26.888 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Sending command GW_GET_NODE_INFORMATION_REQ.
2020-02-17 18:11:29.892 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Received answer GW_GET_NODE_INFORMATION_CFM.
2020-02-17 18:11:29.898 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Received answer GW_GET_NODE_INFORMATION_NTF.
2020-02-17 18:11:29.912 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Sending command GW_GET_NODE_INFORMATION_REQ.
2020-02-17 18:11:32.916 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Received answer GW_GET_NODE_INFORMATION_CFM.
2020-02-17 18:11:32.922 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Received answer GW_GET_NODE_INFORMATION_NTF.
2020-02-17 18:11:32.940 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Sending command GW_GET_NODE_INFORMATION_REQ.
2020-02-17 18:11:35.943 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Received answer GW_GET_NODE_INFORMATION_CFM.
2020-02-17 18:11:35.949 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Received answer GW_GET_NODE_INFORMATION_NTF.
2020-02-17 18:12:12.961 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Sending command GW_GET_NODE_INFORMATION_REQ.
2020-02-17 18:12:15.964 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Received answer GW_GET_NODE_INFORMATION_CFM.
2020-02-17 18:12:15.970 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Received answer GW_GET_NODE_INFORMATION_NTF.
2020-02-17 18:12:15.980 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Sending command GW_GET_NODE_INFORMATION_REQ.
2020-02-17 18:12:18.983 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Received answer GW_GET_NODE_INFORMATION_CFM.
2020-02-17 18:12:18.990 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Received answer GW_GET_NODE_INFORMATION_NTF.
2020-02-17 18:12:19.002 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Sending command GW_GET_NODE_INFORMATION_REQ.
2020-02-17 18:12:22.006 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Received answer GW_GET_NODE_INFORMATION_CFM.
2020-02-17 18:12:22.012 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Received answer GW_GET_NODE_INFORMATION_NTF.
2020-02-17 18:12:22.021 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Sending command GW_GET_NODE_INFORMATION_REQ.
2020-02-17 18:12:25.024 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Received answer GW_GET_NODE_INFORMATION_CFM.
2020-02-17 18:12:25.029 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Received answer GW_GET_NODE_INFORMATION_NTF.
2020-02-17 18:12:25.041 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Sending command GW_GET_STATE_REQ.
2020-02-17 18:12:28.044 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Received answer GW_GET_STATE_CFM.
2020-02-17 18:12:28.052 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Sending command GW_GET_NODE_INFORMATION_REQ.
2020-02-17 18:12:31.055 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Received answer GW_GET_NODE_INFORMATION_CFM.
2020-02-17 18:12:31.061 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Received answer GW_GET_NODE_INFORMATION_NTF.
2020-02-17 18:12:31.070 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Sending command GW_GET_NODE_INFORMATION_REQ.
2020-02-17 18:12:34.073 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Received answer GW_GET_NODE_INFORMATION_CFM.
2020-02-17 18:12:34.079 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Received answer GW_GET_NODE_INFORMATION_NTF.
2020-02-17 18:12:34.102 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Sending command GW_GET_NODE_INFORMATION_REQ.
2020-02-17 18:12:37.105 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Received answer GW_GET_NODE_INFORMATION_CFM.
2020-02-17 18:12:37.111 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Received answer GW_GET_NODE_INFORMATION_NTF.
2020-02-17 18:12:37.123 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Sending command GW_GET_NODE_INFORMATION_REQ.
2020-02-17 18:12:40.125 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Received answer GW_GET_NODE_INFORMATION_CFM.
2020-02-17 18:12:40.128 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Received answer GW_GET_NODE_INFORMATION_NTF.
2020-02-17 18:12:40.136 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Sending command GW_GET_NODE_INFORMATION_REQ.
2020-02-17 18:12:43.139 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Received answer GW_GET_NODE_INFORMATION_CFM.
2020-02-17 18:12:43.145 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Received answer GW_GET_NODE_INFORMATION_NTF.
2020-02-17 18:12:43.159 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Sending command GW_GET_NODE_INFORMATION_REQ.
2020-02-17 18:12:46.162 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Received answer GW_NODE_STATE_POSITION_CHANGED_NTF.
2020-02-17 18:12:46.165 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Received answer GW_NODE_STATE_POSITION_CHANGED_NTF.
2020-02-17 18:12:46.167 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Received answer GW_GET_NODE_INFORMATION_CFM.
2020-02-17 18:12:46.170 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Received answer GW_GET_NODE_INFORMATION_NTF.
2020-02-17 18:12:46.178 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Sending command GW_GET_NODE_INFORMATION_REQ.
2020-02-17 18:12:49.182 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Received answer GW_NODE_STATE_POSITION_CHANGED_NTF.
2020-02-17 18:12:49.186 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Received answer GW_NODE_STATE_POSITION_CHANGED_NTF.
2020-02-17 18:12:49.190 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Received answer GW_NODE_STATE_POSITION_CHANGED_NTF.
2020-02-17 18:12:49.192 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Received answer GW_GET_NODE_INFORMATION_CFM.
2020-02-17 18:12:49.199 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Received answer GW_GET_NODE_INFORMATION_NTF.
2020-02-17 18:12:49.209 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Sending command GW_GET_NODE_INFORMATION_REQ.
2020-02-17 18:12:52.212 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Received answer GW_NODE_STATE_POSITION_CHANGED_NTF.
2020-02-17 18:12:52.215 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Received answer GW_NODE_STATE_POSITION_CHANGED_NTF.
2020-02-17 18:12:52.218 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Received answer GW_NODE_STATE_POSITION_CHANGED_NTF.
2020-02-17 18:12:52.221 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Received answer GW_GET_NODE_INFORMATION_CFM.
2020-02-17 18:12:52.227 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Received answer GW_GET_NODE_INFORMATION_NTF.
2020-02-17 18:12:52.239 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Sending command GW_GET_NODE_INFORMATION_REQ.
2020-02-17 18:12:55.243 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Received answer GW_NODE_STATE_POSITION_CHANGED_NTF.
2020-02-17 18:12:55.246 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Received answer GW_NODE_STATE_POSITION_CHANGED_NTF.
2020-02-17 18:12:55.250 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Received answer GW_NODE_STATE_POSITION_CHANGED_NTF.
2020-02-17 18:12:55.253 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Received answer GW_GET_NODE_INFORMATION_CFM.
2020-02-17 18:12:55.260 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Received answer GW_GET_NODE_INFORMATION_NTF.
2020-02-17 18:13:26.203 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Sending command GW_GET_NODE_INFORMATION_REQ.
2020-02-17 18:13:29.206 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Received answer GW_NODE_STATE_POSITION_CHANGED_NTF.
2020-02-17 18:13:29.210 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Received answer GW_NODE_STATE_POSITION_CHANGED_NTF.
2020-02-17 18:13:29.213 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Received answer GW_GET_NODE_INFORMATION_CFM.
2020-02-17 18:13:29.220 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Received answer GW_GET_NODE_INFORMATION_NTF.
2020-02-17 18:13:29.229 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Sending command GW_GET_NODE_INFORMATION_REQ.
2020-02-17 18:13:32.232 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Received answer GW_GET_NODE_INFORMATION_CFM.
2020-02-17 18:13:32.239 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Received answer GW_GET_NODE_INFORMATION_NTF.
2020-02-17 18:13:32.249 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Sending command GW_GET_NODE_INFORMATION_REQ.
2020-02-17 18:13:35.253 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Received answer GW_GET_NODE_INFORMATION_CFM.
2020-02-17 18:13:35.258 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Received answer GW_GET_NODE_INFORMATION_NTF.
2020-02-17 18:13:35.268 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Sending command GW_GET_NODE_INFORMATION_REQ.
2020-02-17 18:13:38.271 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Received answer GW_GET_NODE_INFORMATION_CFM.
2020-02-17 18:13:38.277 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Received answer GW_GET_NODE_INFORMATION_NTF.
2020-02-17 18:13:38.286 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Sending command GW_GET_STATE_REQ.
2020-02-17 18:13:41.289 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Received answer GW_GET_STATE_CFM.
2020-02-17 18:13:41.298 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Sending command GW_GET_NODE_INFORMATION_REQ.
2020-02-17 18:13:44.300 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Received answer GW_GET_NODE_INFORMATION_CFM.
2020-02-17 18:13:44.304 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Received answer GW_GET_NODE_INFORMATION_NTF.
2020-02-17 18:13:44.309 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Sending command GW_GET_NODE_INFORMATION_REQ.
2020-02-17 18:13:47.312 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Received answer GW_GET_NODE_INFORMATION_CFM.
2020-02-17 18:13:47.319 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Received answer GW_GET_NODE_INFORMATION_NTF.
2020-02-17 18:13:47.331 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Sending command GW_GET_NODE_INFORMATION_REQ.
2020-02-17 18:13:50.333 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Received answer GW_GET_NODE_INFORMATION_CFM.
2020-02-17 18:13:50.336 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Received answer GW_GET_NODE_INFORMATION_NTF.
2020-02-17 18:13:50.343 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Sending command GW_GET_NODE_INFORMATION_REQ.
2020-02-17 18:13:53.346 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Received answer GW_GET_NODE_INFORMATION_CFM.
2020-02-17 18:13:53.352 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Received answer GW_GET_NODE_INFORMATION_NTF.
2020-02-17 18:13:53.366 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Sending command GW_GET_NODE_INFORMATION_REQ.
2020-02-17 18:13:56.369 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Received answer GW_GET_NODE_INFORMATION_CFM.
2020-02-17 18:13:56.374 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Received answer GW_GET_NODE_INFORMATION_NTF.
2020-02-17 18:13:56.386 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Sending command GW_GET_NODE_INFORMATION_REQ.
2020-02-17 18:13:59.389 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Received answer GW_GET_NODE_INFORMATION_CFM.
2020-02-17 18:13:59.396 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Received answer GW_GET_NODE_INFORMATION_NTF.
2020-02-17 18:13:59.406 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Sending command GW_GET_NODE_INFORMATION_REQ.
2020-02-17 18:14:02.409 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Received answer GW_GET_NODE_INFORMATION_CFM.
2020-02-17 18:14:02.416 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Received answer GW_GET_NODE_INFORMATION_NTF.
2020-02-17 18:14:02.426 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Sending command GW_GET_NODE_INFORMATION_REQ.
2020-02-17 18:14:05.429 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Received answer GW_GET_NODE_INFORMATION_CFM.
2020-02-17 18:14:05.435 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Received answer GW_GET_NODE_INFORMATION_NTF.
2020-02-17 18:14:05.445 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Sending command GW_GET_NODE_INFORMATION_REQ.
2020-02-17 18:14:08.448 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Received answer GW_GET_NODE_INFORMATION_CFM.
2020-02-17 18:14:08.454 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Received answer GW_GET_NODE_INFORMATION_NTF.
2020-02-17 18:14:48.489 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Sending command GW_GET_NODE_INFORMATION_REQ.
2020-02-17 18:14:51.492 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Received answer GW_GET_NODE_INFORMATION_CFM.
2020-02-17 18:14:51.498 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Received answer GW_GET_NODE_INFORMATION_NTF.
2020-02-17 18:14:51.507 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Sending command GW_GET_NODE_INFORMATION_REQ.
2020-02-17 18:14:54.510 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Received answer GW_GET_NODE_INFORMATION_CFM.
2020-02-17 18:14:54.516 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Received answer GW_GET_NODE_INFORMATION_NTF.
2020-02-17 18:14:54.526 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Sending command GW_GET_NODE_INFORMATION_REQ.
2020-02-17 18:14:57.529 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Received answer GW_GET_NODE_INFORMATION_CFM.
2020-02-17 18:14:57.535 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Received answer GW_GET_NODE_INFORMATION_NTF.
2020-02-17 18:14:57.545 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - Sending command GW_GET_NODE_INFORMATION_REQ.

Johnny-b · February 20, 2020, 7:40pm

Hi, 4 days agoo i wrote about problems that shutterswhere not recognized, i cleared everything on openhab and removed the binding.
Then i started completely fresh and did it in paper ui and all went smoothly.
All the shutters are recognized and working.
But now iam facing io errors see the log below, has anyone seen this before ?
All the help and info is welcome


2020-02-20 18:19:17.234 [vent.ItemStateChangedEvent] - velux_rollershutter_a0353809_Keuken_position changed from 100 to 0
==> /var/log/openhab2/openhab.log <==
2020-02-20 18:19:17.231 [INFO ] [nternal.bridge.slip.io.SSLconnection] - Starting velux bridge connection.
2020-02-20 18:19:22.704 [WARN ] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate(): received GW_ERROR_NTF (Not authenticated) on GW_GET_NODE_INFORMATION_REQ, aborting.
2020-02-20 18:19:22.710 [INFO ] [.internal.handler.VeluxBridgeHandler] - handleCommandScheduled(velux:rollershutter:a0353809:Keuken:position,REFRESH): updating of item velux:rollershutter:a0353809:Keuken:position (type velux:rollershutter/position) failed.
2020-02-20 18:19:23.737 [WARN ] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate(): connection.io returns -1
2020-02-20 18:19:23.741 [INFO ] [.internal.handler.VeluxBridgeHandler] - handleCommandScheduled(velux:rollershutter:a0353809:Slaapkamer:position,REFRESH): updating of item velux:rollershutter:a0353809:Slaapkamer:position (type velux:rollershutter/position) failed.
2020-02-20 18:19:23.748 [INFO ] [x.internal.bridge.slip.io.Connection] - io(): raised an error during sending: Connection or outbound has closed.
2020-02-20 18:19:23.752 [INFO ] [nternal.bridge.slip.io.SSLconnection] - Shutting down Velux bridge connection.
2020-02-20 18:19:23.756 [WARN ] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate(): connection.io returns Unexpected I/O exception.
2020-02-20 18:19:23.759 [INFO ] [.internal.handler.VeluxBridgeHandler] - handleCommandScheduled(velux:klf200:a0353809:status,REFRESH): updating of item velux:klf200:a0353809:status (type velux:klf200/status) failed.
2020-02-20 18:19:23.767 [INFO ] [nternal.bridge.slip.io.SSLconnection] - Starting velux bridge connection.
2020-02-20 18:19:29.212 [INFO ] [g.velux.internal.bridge.slip.SClogin] - velux bridge connection successfully established (login succeeded).
==> /var/log/openhab2/events.log <==
2020-02-20 18:20:22.306 [vent.ItemStateChangedEvent] - velux_rollershutter_a0353809_Keuken_position changed from 0 to 100
2020-02-20 18:34:22.597 [vent.ItemStateChangedEvent] - velux_rollershutter_a0353809_Keuken_position changed from 100 to 0
2020-02-20 18:35:24.639 [vent.ItemStateChangedEvent] - velux_rollershutter_a0353809_Keuken_position changed from 0 to 100
2020-02-20 18:41:00.003 [vent.ChannelTriggeredEvent] - astro:sun:home:nauticDusk#event triggered START
2020-02-20 18:41:00.008 [vent.ChannelTriggeredEvent] - astro:sun:home:civilDusk#event triggered END
2020-02-20 19:15:00.046 [ome.event.ItemCommandEvent] - Item 'velux_rollershutter_a0353809_Slaapkamer_position' received command 50
2020-02-20 19:15:00.086 [nt.ItemStatePredictedEvent] - velux_rollershutter_a0353809_Slaapkamer_position predicted to become 50
2020-02-20 19:15:00.119 [vent.ItemStateChangedEvent] - velux_rollershutter_a0353809_Slaapkamer_position changed from 100 to 50
==> /var/log/openhab2/openhab.log <==
2020-02-20 19:15:00.251 [INFO ] [.eclipse.smarthome.model.script.info] - Rolluik slaapkamer gaat 50 % dicht! 
2020-02-20 19:15:02.353 [INFO ] [x.internal.bridge.slip.io.Connection] - io(): Exception occurred during I/O: read timeout.
2020-02-20 19:15:04.358 [INFO ] [x.internal.bridge.slip.io.Connection] - io(): Exception occurred during I/O: read timeout.
2020-02-20 19:15:07.362 [INFO ] [x.internal.bridge.slip.io.Connection] - io(): Exception occurred during I/O: read timeout.
2020-02-20 19:15:12.366 [INFO ] [x.internal.bridge.slip.io.Connection] - io(): Exception occurred during I/O: read timeout.
2020-02-20 19:15:21.375 [INFO ] [x.internal.bridge.slip.io.Connection] - io(): Exception occurred during I/O: read timeout.
2020-02-20 19:15:38.381 [INFO ] [x.internal.bridge.slip.io.Connection] - io(): Exception occurred during I/O: read timeout.
2020-02-20 19:16:10.384 [INFO ] [x.internal.bridge.slip.io.Connection] - io(): socket I/O failed 5 times.
2020-02-20 19:16:10.388 [INFO ] [nternal.bridge.slip.io.SSLconnection] - Shutting down Velux bridge connection.
2020-02-20 19:16:10.400 [WARN ] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate(): connection.io returns read timeout
2020-02-20 19:16:30.427 [INFO ] [nternal.bridge.slip.io.SSLconnection] - Starting velux bridge connection.
==> /var/log/openhab2/events.log <==
2020-02-20 19:16:30.428 [vent.ItemStateChangedEvent] - velux_rollershutter_a0353809_Slaapkamer_position changed from 50 to 100
==> /var/log/openhab2/openhab.log <==
2020-02-20 19:16:35.879 [WARN ] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate(): received GW_ERROR_NTF (Not authenticated) on GW_GET_NODE_INFORMATION_REQ, aborting.
2020-02-20 19:16:35.882 [INFO ] [.internal.handler.VeluxBridgeHandler] - handleCommandScheduled(velux:rollershutter:a0353809:Keuken:position,REFRESH): updating of item velux:rollershutter:a0353809:Keuken:position (type velux:rollershutter/position) failed.
2020-02-20 19:16:36.918 [WARN ] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate(): connection.io returns -1

Is it a hardware problem on the klf200 or a network problem ?

EjvindHald · February 20, 2020, 8:22pm

Try to increase the timeoutMsecs + retries settings on the Velux bridge. I am using 4000 and 10 for these.

EjvindHald · February 20, 2020, 8:30pm

I have now the rain sensor working very fine. Example of things file is:

Bridge velux:klf200:home [ ipAddress=“192.168.1.15”, tcpPort=51200, password=“velux123”, timeoutMsecs=4000, retries=10 ]
{
Thing actuator ThKLF200Vindue “Velux - Vindue” [serial=“53:07:09:26:0C:0D:00:5F”]
Thing actuator ThKLF200Rulleskodde “Velux - Rulleskodde” [serial=“56:23:3D:5A:0A:21:1A:46”]
Thing actuator ThKLF200Gardin “Velux - Gardin” [serial=“53:14:1E:32:13:25:04:39”]
}
where the window is ThKLF200Vindue

The item file looks like this:

Rollershutter KLF200Vindue “KLF vindue [%d]” { channel=“velux:actuator:home:ThKLF200Vindue:position” }
Rollershutter KLF200Rulleskodde “Rulleskodde” { channel=“velux:actuator:home:ThKLF200Rulleskodde:position” }
Rollershutter KLF200Gardin “Gardin” { channel=“velux:actuator:home:ThKLF200Gardin:position” }
Rollershutter KLF200VindueLimitMinimum “KLF vindue minimum [%.0f]” { channel=“velux:actuator:home:ThKLF200Vindue:limitMinimum” }
Contact UdendoersRegnsensor “Regnsensor [MAP(enonoff.map):%s]”

Note there are 2 channels linked to ThKLF200Vindue. The contact UdendoersRegnsensor is the item holding the current status of the rain sensor.

I have made this simple rule to support this:

//Udendørs regnsensor
rule “Udendørs regnsensor”
when Item KLF200VindueLimitMinimum changed
then
if(KLF200VindueLimitMinimum.state == 0) UdendoersRegnsensor.sendCommand(CLOSED) else UdendoersRegnsensor.sendCommand(OPEN)
end

and I have not inverted the window. If it is inverted the number 0 should be changed to 100.

So now I have a new rain sensor in my setup, which can be used to control other devices
Thanks @gs4711

Johnny-b · February 20, 2020, 9:24pm

Thanks for your reply, i will give it a try !

gs4711 · February 21, 2020, 6:09am

Hello @Johnny-b,

please could you share the

number of actuators and scenes,
bridge parameters settings (timeout, retries).
to elaborate the possible reasons for the timeouts. Let’s see whether there is something unexpected…

Johnny-b · February 21, 2020, 10:51am

Hi Guenther, here are the things you asked for,

I am running on Openhab 2.5.1 and binding org.openhab.binding.velux-2.5.1.202001051139 And on a RPI 3B + with Buster installed


2020-02-21 11:45:30.929 [INFO ] [g.velux.internal.bridge.slip.SClogin] - velux bridge connection successfully established (login succeeded).
2020-02-21 11:45:37.110 [INFO ] [.internal.handler.VeluxBridgeHandler] - Found velux scenes:
	Scene "Keuken openen" (index 3) with non-silent mode and 0 actions
	Scene "Badkamer sluiten" (index 6) with non-silent mode and 0 actions
	Scene "Zolder sluiten" (index 16) with non-silent mode and 0 actions
	Scene "Zolder openen" (index 17) with non-silent mode and 0 actions
	Scene "Badkamer 80%" (index 12) with non-silent mode and 0 actions
	Scene "Badkamer 50%" (index 11) with non-silent mode and 0 actions
	Scene "Slaapkamer 80%" (index 10) with non-silent mode and 0 actions
	Scene "Badkamer openen" (index 7) with non-silent mode and 0 actions
	Scene "slaapkamer sluiten" (index 0) with non-silent mode and 0 actions
	Scene "Slaapkamer openen" (index 1) with non-silent mode and 0 actions
	Scene "Keuken 50%" (index 13) with non-silent mode and 0 actions
	Scene "Zolder 80%" (index 19) with non-silent mode and 0 actions
	Scene "Keuken sluiten" (index 2) with non-silent mode and 0 actions
	Scene "Slaapkamer 50%" (index 9) with non-silent mode and 0 actions
	Scene "Keuken 75%" (index 8) with non-silent mode and 0 actions
	Scene "Zolder 50%" (index 18) with non-silent mode and 0 actions	
2020-02-21 11:45:40.179 [INFO ] [.internal.handler.VeluxBridgeHandler] - Found velux actuators:
	Product "Badkamer" / SLIDER_SHUTTER (bridgeIndex=3,serial=53:2A:5D:5A:12:10:1A:BD,position=6400)
	Product "Zolder" / SLIDER_SHUTTER (bridgeIndex=4,serial=53:2A:5D:5A:12:10:3B:3C,position=9000)
	Product "Slaapkamer" / SLIDER_SHUTTER (bridgeIndex=0,serial=53:2A:5D:5A:12:10:3A:D9,position=C800)
	Product "Keuken" / SLIDER_SHUTTER (bridgeIndex=1,serial=Keuken,position=0000)	
2020-02-21 11:45:43.186 [INFO ] [.internal.handler.VeluxBridgeHandler] - velux Bridge is online with 16 scenes and 4 actuators, now.

```csv

Johnny-b · February 21, 2020, 9:32pm

Hi Guenther,
meanwhile my sd card seems to crash and now Openhab does not start anymore.
In the weekend i will try to start with a new card and a image of a backup.
So don’t spend time on this at the moment.
Thanks for your time so far.

gs4711 · February 22, 2020, 3:35pm

Good luck.

gs4711 · February 25, 2020, 10:58pm

Finally the binding is included in 2.5.3. Jarfile is available as usual at github.

Kim_Andersen · February 26, 2020, 10:27pm

Great job Guenther!

csi_oh · February 27, 2020, 2:15pm

Congratulations and again: many, many thanks for your efforts and time, especially helping out here in the forum! KR

funcat · March 2, 2020, 4:54pm

Hi all,
I installed the plugin v2.5.3-SNAPSHOT yesterday (i though perhaps it would fix my somfy MY bug …), and i am confused about this version.
I have several question about this :

My scenes were not recognized at all at the 1st restart. And i restarted again, same result. And i renamed them in the thing file (because i use the ‘§’ symbol, maybe cause ?) and scenes were recognized. And i renamed them with the ‘§’ and now they are working … strange isn’t it ?
Exemple of thing scene :

Thing	scene	klf200_maisonRue_close			"§maison - volets maisonRue_close (Velux KLF200)"			@ "maison"	[ sceneName="maisonRue_close" ]

For items, i understand now the format and 2 possible declarations in items file, both are working, is it safe at long term ? which is the good one ? :

Rollershutter	bureauVolet			"Volet bureau [%d]"			{ channel="velux:klf200:home:klf200_bureau:position" }
Rollershutter	bureauVolet2		"Volet bureau2 [%d]"		{ channel="velux:rollershutter:home:klf200_bureau:position" }

Errors and logs. I cannot explain many logs like (very frequent) :

2020-03-02 17:32:09.448 [WARN ] [al.handler.utils.Thing2VeluxActuator] - mapThing2Velux(): aborting processing as serial is not set.
2020-03-02 17:32:49.465 [WARN ] [al.handler.utils.Thing2VeluxActuator] - mapThing2Velux(): aborting processing as serial is not set.
2020-03-02 17:32:54.520 [WARN ] [al.handler.utils.Thing2VeluxActuator] - mapThing2Velux(): aborting processing as serial is not set.

Or : (x30 frequently … and i have 30 scenes) :

2020-03-02 17:02:46.940 [WARN ] [nal.handler.utils.ThingConfiguration] - exists(): Channel velux:klf200:home:check does not belong to a thing.

Or (every hours) :

2020-03-02 17:02:47.056 [INFO ] [.internal.handler.ChannelBridgeCheck] - Integrity check failed: The following scenes are unused:[cuisine_open, maisonHautRue_my, maisonRue_my, maisonHautJardin_my, maisonHautJardin_open, maison_open, maisonBasRue_my, maisonHautJardin_close, maisonBasJardin_my, maisonBas_my, maisonBasJardin_open, maisonHaut_open, maisonJardin_close, cuisine_my, maisonJardin_my, maisonHaut_my, maisonRue_open, cuisine_close, maison_close, maisonBas_close, maisonJardin_open, maisonBasRue_close, maisonBas_open, maisonHautRue_close, maisonHautRue_open, maisonBasRue_open, maisonBasJardin_close, maisonRue_close, maisonHaut_close, maison_my]

Edit : new warn/error logs weird :

2020-03-02 19:10:28.482 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate(): received GW_ACTIVATION_LOG_UPDATED_NTF.
2020-03-02 19:10:28.500 [INFO ] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate(): received GW_ACTIVATION_LOG_UPDATED_NTF.

Just for info, with previous version i had no warn logs like this.

TLTR :
It is working but i have a lot of warn/error logs, i am trying to understand …

PS : and i still cannot see updated position ‘my’ for my somfy shutters but this another story.