04:35:58.259 [TRACE] [nhab.binding.velux.bridge.VeluxBridge] - bridgeCommunicate(Get Bridge Device Status) called.
04:35:58.261 [TRACE] [nhab.binding.velux.bridge.VeluxBridge] - isAuthenticated() returns true.
04:35:58.263 [TRACE] [nhab.binding.velux.bridge.VeluxBridge] - bridgeCommunicate(Get Bridge Device Status,authenticated) called.
04:35:58.266 [TRACE] [nhab.binding.velux.bridge.VeluxBridge] - isAuthenticated() returns true.
04:35:58.268 [TRACE] [ing.velux.bridge.slip.SlipVeluxBridge] - bridgeAPI() called.
04:35:58.270 [TRACE] [nding.velux.bridge.slip.SlipBridgeAPI] - bridgeDirectCommunicate(org.openhab.binding.velux.bridge.slip.SCgetDeviceStatus@1480507,true) called.
04:35:58.273 [TRACE] [ing.velux.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate(Get Bridge Device Status,authenticated) called.
04:35:58.275 [DEBUG] [g.velux.bridge.slip.SCgetDeviceStatus] - getRequestCommand() returns GW_GET_STATE_REQ (0xc).
04:35:58.277 [TRACE] [g.velux.bridge.slip.SCgetDeviceStatus] - getRequestDataAsArrayOfBytes() returns data.
04:35:58.279 [DEBUG] [ing.velux.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate(GW_GET_STATE_REQ,authenticated) called.
04:35:58.282 [DEBUG] [ing.velux.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate(): working on request GW_GET_STATE_REQ with 0 bytes of data.
04:35:58.284 [TRACE] [g.velux.bridge.slip.util.SlipEncoding] - SlipEncoding(constructor) for command 0xc with data size 0 called.
04:35:58.287 [TRACE] [g.velux.bridge.slip.util.SlipEncoding] - SlipEncoding(constructor) successfully initialized, storing bytes: 00 03 00 0C 0F.
04:35:58.290 [TRACE] [ing.velux.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate(): transportEncoding=00 03 00 0C 0F.
04:35:58.292 [TRACE] [ng.velux.bridge.slip.util.SlipRFC1055] - encode() for data size 5 called.
04:35:58.295 [TRACE] [ng.velux.bridge.slip.util.SlipRFC1055] - encode() provides transfer encoding: C0 00 03 00 0C 0F C0.
04:35:58.297 [TRACE] [ing.velux.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate(): sending 7 bytes to 192.168.178.22:51200.
04:35:58.299 [TRACE] [ing.velux.bridge.slip.SlipVeluxBridge] - io(192.168.178.22,51200,7 bytes) called.
04:35:58.301 [TRACE] [ing.velux.bridge.slip.SlipVeluxBridge] - io(): sending packet with 7 bytes: C0 00 03 00 0C 0F C0
04:35:58.303 [TRACE] [ng.velux.bridge.slip.io.SSLconnection] - send() called, writing 7 bytes.
04:35:58.305 [WARN ] [ing.velux.bridge.slip.SlipVeluxBridge] - io(): raised an error during sending: Connection closed by remote host.
04:35:58.307 [TRACE] [ing.velux.bridge.slip.SlipVeluxBridge] - io(): wait time 2000 msecs.
04:36:00.309 [TRACE] [ing.velux.bridge.slip.SlipVeluxBridge] - io(): receiving bytes.
04:36:00.314 [TRACE] [ng.velux.bridge.slip.io.SSLconnection] - receive() called.
04:36:00.318 [WARN ] [ing.velux.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate(): method io() raised an error: null.
04:36:00.323 [DEBUG] [ing.velux.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate(GW_GET_STATE_REQ) returns failure.
04:36:00.327 [TRACE] [.velux.bridge.VeluxBridgeDeviceStatus] - retrieve() finished with failure.
04:36:00.331 [INFO ] [g.velux.handler.VeluxBridgeHandlerOH1] - handleCommandOnChannel(): updating of item velux_bridge_status (type BRIDGE_STATUS) failed.
04:36:00.335 [TRACE] [g.velux.handler.VeluxBridgeHandlerOH1] - handleCommandOnChannel() done.
04:36:00.338 [TRACE] [.internal.VeluxGenericBindingProvider] - getConfigForItemName(velux_rollershutter_SZ) called.
04:36:00.342 [TRACE] [.internal.VeluxGenericBindingProvider] - getConfigForItemName() returns 53:2A:5D:5A:13:05:15:18.
04:36:00.346 [TRACE] [.binding.velux.internal.VeluxItemType] - isToBeRefreshed() returns true.
04:36:00.350 [TRACE] [.binding.velux.internal.VeluxItemType] - getRefreshDivider() returns 20.
04:36:00.353 [TRACE] [b.binding.velux.internal.VeluxBinding] - execute(): refresh cycle not yet come for item velux_rollershutter_SZ.
04:36:00.357 [TRACE] [.internal.VeluxGenericBindingProvider] - getConfigForItemName(velux_bridge_check) called.
04:36:00.361 [TRACE] [.internal.VeluxGenericBindingProvider] - getConfigForItemName() returns thing=bridge;channel=check.
04:36:00.364 [TRACE] [.binding.velux.internal.VeluxItemType] - isToBeRefreshed() returns true.
04:36:00.368 [TRACE] [.binding.velux.internal.VeluxItemType] - getRefreshDivider() returns 5760.
04:36:00.371 [TRACE] [b.binding.velux.internal.VeluxBinding] - execute(): refresh cycle not yet come for item velux_bridge_check.
04:36:00.375 [TRACE] [.internal.VeluxGenericBindingProvider] - getConfigForItemName(velux_bridge_scenes) called.
04:36:00.378 [TRACE] [.internal.VeluxGenericBindingProvider] - getConfigForItemName() returns thing=bridge;channel=scenes.
04:36:00.381 [TRACE] [.binding.velux.internal.VeluxItemType] - isToBeRefreshed() returns true.
04:36:00.385 [TRACE] [.binding.velux.internal.VeluxItemType] - getRefreshDivider() returns 5760.
04:36:00.389 [TRACE] [b.binding.velux.internal.VeluxBinding] - execute(): refresh cycle not yet come for item velux_bridge_scenes.
04:36:00.393 [TRACE] [.internal.VeluxGenericBindingProvider] - getConfigForItemName(velux_rollershutter_O_WZ) called.
04:36:00.397 [TRACE] [.internal.VeluxGenericBindingProvider] - getConfigForItemName() returns 53:2A:5D:5A:13:05:15:1F.
04:36:00.399 [TRACE] [.binding.velux.internal.VeluxItemType] - isToBeRefreshed() returns true.
04:36:00.401 [TRACE] [.binding.velux.internal.VeluxItemType] - getRefreshDivider() returns 20.
04:36:00.403 [TRACE] [b.binding.velux.internal.VeluxBinding] - execute(): refresh cycle not yet come for item velux_rollershutter_O_WZ.
04:36:00.405 [DEBUG] [b.binding.velux.internal.VeluxBinding] - execute() done.
04:36:15.407 [DEBUG] [b.binding.velux.internal.VeluxBinding] - execute() called.
04:36:15.425 [TRACE] [b.binding.velux.internal.VeluxBinding] - execute(): working with VeluxBindingProvider org.openhab.binding.velux.internal.VeluxGenericBindingProvider@1c68eaa.
04:36:15.436 [TRACE] [.internal.VeluxGenericBindingProvider] - getInBindingItemNames() returns [velux_rollershutter_MZ, velux_rollershutter_W_WZ, velux_rollershutter_B, velux_bridge_products, velux_rollershutter_K, velux_rollershutter_FZ, velux_bridge_status, velux_rollershutter_SZ, velux_bridge_check, velux_bridge_scenes, velux_rollershutter_O_WZ].
04:36:15.443 [TRACE] [.internal.VeluxGenericBindingProvider] - getConfigForItemName(velux_rollershutter_MZ) called.
04:36:15.450 [TRACE] [.internal.VeluxGenericBindingProvider] - getConfigForItemName() returns 53:2A:5D:5A:13:05:0A:7F.
04:36:15.458 [TRACE] [.binding.velux.internal.VeluxItemType] - isToBeRefreshed() returns true.
04:36:15.465 [TRACE] [.binding.velux.internal.VeluxItemType] - getRefreshDivider() returns 20.
04:36:15.476 [TRACE] [b.binding.velux.internal.VeluxBinding] - execute(): refresh cycle not yet come for item velux_rollershutter_MZ.
04:36:15.484 [TRACE] [.internal.VeluxGenericBindingProvider] - getConfigForItemName(velux_rollershutter_W_WZ) called.
04:36:15.492 [TRACE] [.internal.VeluxGenericBindingProvider] - getConfigForItemName() returns 53:2A:5D:5A:13:05:0C:C3.
04:36:15.500 [TRACE] [.binding.velux.internal.VeluxItemType] - isToBeRefreshed() returns true.
04:36:15.508 [TRACE] [.binding.velux.internal.VeluxItemType] - getRefreshDivider() returns 20.
04:36:15.519 [TRACE] [b.binding.velux.internal.VeluxBinding] - execute(): refresh cycle not yet come for item velux_rollershutter_W_WZ.
04:36:15.523 [TRACE] [.internal.VeluxGenericBindingProvider] - getConfigForItemName(velux_rollershutter_B) called.
04:36:15.526 [TRACE] [.internal.VeluxGenericBindingProvider] - getConfigForItemName() returns 53:2A:5D:5A:13:05:0A:54.
04:36:15.531 [TRACE] [.binding.velux.internal.VeluxItemType] - isToBeRefreshed() returns true.
04:36:15.534 [TRACE] [.binding.velux.internal.VeluxItemType] - getRefreshDivider() returns 20.
04:36:15.542 [TRACE] [b.binding.velux.internal.VeluxBinding] - execute(): refresh cycle not yet come for item velux_rollershutter_B.
04:36:15.545 [TRACE] [.internal.VeluxGenericBindingProvider] - getConfigForItemName(velux_bridge_products) called.
04:36:15.549 [TRACE] [.internal.VeluxGenericBindingProvider] - getConfigForItemName() returns thing=bridge;channel=products.
04:36:15.553 [TRACE] [.binding.velux.internal.VeluxItemType] - isToBeRefreshed() returns true.
04:36:15.557 [TRACE] [.binding.velux.internal.VeluxItemType] - getRefreshDivider() returns 15.
04:36:15.562 [TRACE] [b.binding.velux.internal.VeluxBinding] - execute(): refresh cycle not yet come for item velux_bridge_products.
04:36:15.566 [TRACE] [.internal.VeluxGenericBindingProvider] - getConfigForItemName(velux_rollershutter_K) called.
04:36:15.570 [TRACE] [.internal.VeluxGenericBindingProvider] - getConfigForItemName() returns 53:2A:5D:5A:13:05:16:13.
04:36:15.574 [TRACE] [.binding.velux.internal.VeluxItemType] - isToBeRefreshed() returns true.
04:36:15.578 [TRACE] [.binding.velux.internal.VeluxItemType] - getRefreshDivider() returns 20.
04:36:15.582 [TRACE] [b.binding.velux.internal.VeluxBinding] - execute(): refresh cycle not yet come for item velux_rollershutter_K.
04:36:15.586 [TRACE] [.internal.VeluxGenericBindingProvider] - getConfigForItemName(velux_rollershutter_FZ) called.
04:36:15.590 [TRACE] [.internal.VeluxGenericBindingProvider] - getConfigForItemName() returns 53:2A:5D:5A:13:05:15:30.
04:36:15.595 [TRACE] [.binding.velux.internal.VeluxItemType] - isToBeRefreshed() returns true.
04:36:15.598 [TRACE] [.binding.velux.internal.VeluxItemType] - getRefreshDivider() returns 20.
04:36:15.602 [TRACE] [b.binding.velux.internal.VeluxBinding] - execute(): refresh cycle not yet come for item velux_rollershutter_FZ.
04:36:15.606 [TRACE] [.internal.VeluxGenericBindingProvider] - getConfigForItemName(velux_bridge_status) called.
04:36:15.611 [TRACE] [.internal.VeluxGenericBindingProvider] - getConfigForItemName() returns thing=bridge;channel=status.
04:36:15.616 [TRACE] [.binding.velux.internal.VeluxItemType] - isToBeRefreshed() returns true.
04:36:15.619 [TRACE] [.binding.velux.internal.VeluxItemType] - getRefreshDivider() returns 1.
04:36:15.623 [TRACE] [b.binding.velux.internal.VeluxBinding] - execute(): refreshing item velux_bridge_status.
04:36:15.627 [DEBUG] [g.velux.handler.VeluxBridgeHandlerOH1] - handleCommandOnChannel(item=velux_bridge_status,command=null,config=org.openhab.binding.velux.internal.VeluxBindingConfig@1e7212e,provider=org.openhab.binding.velux.internal.VeluxGenericBindingProvider@1c68eaa) called.
04:36:15.630 [TRACE] [ing.velux.bridge.VeluxBridgeActuators] - updateOH() called.
04:36:15.634 [TRACE] [ng.velux.things.VeluxExistingProducts] - isDirty() returns false.
04:36:15.639 [TRACE] [ing.velux.bridge.VeluxBridgeActuators] - updateOH() finished.
04:36:15.642 [TRACE] [g.velux.handler.VeluxBridgeHandlerOH1] - handleCommandOnChannel(): openHAB items updated.
04:36:15.646 [TRACE] [g.velux.handler.VeluxBridgeHandlerOH1] - handleCommandOnChannel(): work on refresh.
04:36:15.649 [TRACE] [.internal.VeluxGenericBindingProvider] - getConfigForItemName(velux_bridge_status) called.
04:36:15.653 [TRACE] [.internal.VeluxGenericBindingProvider] - getConfigForItemName() returns thing=bridge;channel=status.
04:36:15.658 [TRACE] [.binding.velux.internal.VeluxItemType] - isReadable() returns true.
04:36:15.661 [TRACE] [g.velux.handler.VeluxBridgeHandlerOH1] - handleCommandOnChannel(): refreshing item velux_bridge_status.
04:36:15.666 [TRACE] [.velux.bridge.VeluxBridgeDeviceStatus] - VeluxBridgeDeviceStatus(constructor) called.
04:36:15.669 [TRACE] [.velux.bridge.VeluxBridgeDeviceStatus] - retrieve() called. About to query device status.
04:36:15.673 [TRACE] [ing.velux.bridge.slip.SlipVeluxBridge] - bridgeAPI() called.
04:36:15.678 [TRACE] [nhab.binding.velux.bridge.VeluxBridge] - bridgeCommunicate(Get Bridge Device Status) called.
04:36:15.682 [TRACE] [nhab.binding.velux.bridge.VeluxBridge] - isAuthenticated() returns true.
04:36:15.687 [TRACE] [nhab.binding.velux.bridge.VeluxBridge] - bridgeCommunicate(Get Bridge Device Status,authenticated) called.
04:36:15.690 [TRACE] [nhab.binding.velux.bridge.VeluxBridge] - isAuthenticated() returns true.
04:36:15.694 [TRACE] [ing.velux.bridge.slip.SlipVeluxBridge] - bridgeAPI() called.
04:36:15.698 [TRACE] [nding.velux.bridge.slip.SlipBridgeAPI] - bridgeDirectCommunicate(org.openhab.binding.velux.bridge.slip.SCgetDeviceStatus@1480507,true) called.
04:36:15.702 [TRACE] [ing.velux.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate(Get Bridge Device Status,authenticated) called.
04:36:15.706 [DEBUG] [g.velux.bridge.slip.SCgetDeviceStatus] - getRequestCommand() returns GW_GET_STATE_REQ (0xc).
04:36:15.711 [TRACE] [g.velux.bridge.slip.SCgetDeviceStatus] - getRequestDataAsArrayOfBytes() returns data.
04:36:15.714 [DEBUG] [ing.velux.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate(GW_GET_STATE_REQ,authenticated) called.
04:36:15.719 [DEBUG] [ing.velux.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate(): working on request GW_GET_STATE_REQ with 0 bytes of data.
04:36:15.723 [TRACE] [g.velux.bridge.slip.util.SlipEncoding] - SlipEncoding(constructor) for command 0xc with data size 0 called.
04:36:15.728 [TRACE] [g.velux.bridge.slip.util.SlipEncoding] - SlipEncoding(constructor) successfully initialized, storing bytes: 00 03 00 0C 0F.
04:36:15.732 [TRACE] [ing.velux.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate(): transportEncoding=00 03 00 0C 0F.
04:36:15.737 [TRACE] [ng.velux.bridge.slip.util.SlipRFC1055] - encode() for data size 5 called.
04:36:15.741 [TRACE] [ng.velux.bridge.slip.util.SlipRFC1055] - encode() provides transfer encoding: C0 00 03 00 0C 0F C0.
04:36:15.745 [TRACE] [ing.velux.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate(): sending 7 bytes to 192.168.178.22:51200.
04:36:15.749 [TRACE] [ing.velux.bridge.slip.SlipVeluxBridge] - io(192.168.178.22,51200,7 bytes) called.
04:36:15.754 [TRACE] [ing.velux.bridge.slip.SlipVeluxBridge] - io(): sending packet with 7 bytes: C0 00 03 00 0C 0F C0
04:36:15.757 [TRACE] [ng.velux.bridge.slip.io.SSLconnection] - send() called, writing 7 bytes.
04:36:15.761 [WARN ] [ing.velux.bridge.slip.SlipVeluxBridge] - io(): raised an error during sending: Connection closed by remote host.
04:36:15.765 [TRACE] [ing.velux.bridge.slip.SlipVeluxBridge] - io(): wait time 2000 msecs.
04:36:17.769 [TRACE] [ing.velux.bridge.slip.SlipVeluxBridge] - io(): receiving bytes.
04:36:17.776 [TRACE] [ng.velux.bridge.slip.io.SSLconnection] - receive() called.
04:36:17.783 [WARN ] [ing.velux.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate(): method io() raised an error: null.
04:36:17.789 [DEBUG] [ing.velux.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate(GW_GET_STATE_REQ) returns failure.
04:36:17.797 [TRACE] [.velux.bridge.VeluxBridgeDeviceStatus] - retrieve() finished with failure.
04:36:17.804 [INFO ] [g.velux.handler.VeluxBridgeHandlerOH1] - handleCommandOnChannel(): updating of item velux_bridge_status (type BRIDGE_STATUS) failed.
04:36:17.811 [TRACE] [g.velux.handler.VeluxBridgeHandlerOH1] - handleCommandOnChannel() done.
04:36:17.818 [TRACE] [.internal.VeluxGenericBindingProvider] - getConfigForItemName(velux_rollershutter_SZ) called.
04:36:17.825 [TRACE] [.internal.VeluxGenericBindingProvider] - getConfigForItemName() returns 53:2A:5D:5A:13:05:15:18.
04:36:17.831 [TRACE] [.binding.velux.internal.VeluxItemType] - isToBeRefreshed() returns true.
04:36:17.837 [TRACE] [.binding.velux.internal.VeluxItemType] - getRefreshDivider() returns 20.
04:36:17.841 [TRACE] [b.binding.velux.internal.VeluxBinding] - execute(): refresh cycle not yet come for item velux_rollershutter_SZ.
04:36:17.845 [TRACE] [.internal.VeluxGenericBindingProvider] - getConfigForItemName(velux_bridge_check) called.
04:36:17.849 [TRACE] [.internal.VeluxGenericBindingProvider] - getConfigForItemName() returns thing=bridge;channel=check.
04:36:17.853 [TRACE] [.binding.velux.internal.VeluxItemType] - isToBeRefreshed() returns true.
04:36:17.857 [TRACE] [.binding.velux.internal.VeluxItemType] - getRefreshDivider() returns 5760.
04:36:17.861 [TRACE] [b.binding.velux.internal.VeluxBinding] - execute(): refresh cycle not yet come for item velux_bridge_check.
04:36:17.865 [TRACE] [.internal.VeluxGenericBindingProvider] - getConfigForItemName(velux_bridge_scenes) called.
04:36:17.868 [TRACE] [.internal.VeluxGenericBindingProvider] - getConfigForItemName() returns thing=bridge;channel=scenes.
04:36:17.872 [TRACE] [.binding.velux.internal.VeluxItemType] - isToBeRefreshed() returns true.
04:36:17.876 [TRACE] [.binding.velux.internal.VeluxItemType] - getRefreshDivider() returns 5760.
04:36:17.881 [TRACE] [b.binding.velux.internal.VeluxBinding] - execute(): refresh cycle not yet come for item velux_bridge_scenes.
04:36:17.887 [TRACE] [.internal.VeluxGenericBindingProvider] - getConfigForItemName(velux_rollershutter_O_WZ) called.
04:36:17.890 [TRACE] [.internal.VeluxGenericBindingProvider] - getConfigForItemName() returns 53:2A:5D:5A:13:05:15:1F.
04:36:17.894 [TRACE] [.binding.velux.internal.VeluxItemType] - isToBeRefreshed() returns true.
04:36:17.898 [TRACE] [.binding.velux.internal.VeluxItemType] - getRefreshDivider() returns 20.
04:36:17.903 [TRACE] [b.binding.velux.internal.VeluxBinding] - execute(): refresh cycle not yet come for item velux_rollershutter_O_WZ.
04:36:17.906 [DEBUG] [b.binding.velux.internal.VeluxBinding] - execute() done.
04:36:32.910 [DEBUG] [b.binding.velux.internal.VeluxBinding] - execute() called.
04:36:32.917 [TRACE] [b.binding.velux.internal.VeluxBinding] - execute(): working with VeluxBindingProvider org.openhab.binding.velux.internal.VeluxGenericBindingProvider@1c68eaa.
04:36:32.926 [TRACE] [.internal.VeluxGenericBindingProvider] - getInBindingItemNames() returns [velux_rollershutter_MZ, velux_rollershutter_W_WZ, velux_rollershutter_B, velux_bridge_products, velux_rollershutter_K, velux_rollershutter_FZ, velux_bridge_status, velux_rollershutter_SZ, velux_bridge_check, velux_bridge_scenes, velux_rollershutter_O_WZ].
04:36:32.935 [TRACE] [.internal.VeluxGenericBindingProvider] - getConfigForItemName(velux_rollershutter_MZ) called.
04:36:32.940 [TRACE] [.internal.VeluxGenericBindingProvider] - getConfigForItemName() returns 53:2A:5D:5A:13:05:0A:7F.
04:36:32.946 [TRACE] [.binding.velux.internal.VeluxItemType] - isToBeRefreshed() returns true.
04:36:32.951 [TRACE] [.binding.velux.internal.VeluxItemType] - getRefreshDivider() returns 20.
04:36:32.957 [TRACE] [b.binding.velux.internal.VeluxBinding] - execute(): refresh cycle not yet come for item velux_rollershutter_MZ.
04:36:32.963 [TRACE] [.internal.VeluxGenericBindingProvider] - getConfigForItemName(velux_rollershutter_W_WZ) called.
04:36:32.972 [TRACE] [.internal.VeluxGenericBindingProvider] - getConfigForItemName() returns 53:2A:5D:5A:13:05:0C:C3.
04:36:32.980 [TRACE] [.binding.velux.internal.VeluxItemType] - isToBeRefreshed() returns true.
04:36:32.985 [TRACE] [.binding.velux.internal.VeluxItemType] - getRefreshDivider() returns 20.
04:36:32.988 [TRACE] [b.binding.velux.internal.VeluxBinding] - execute(): refresh cycle not yet come for item velux_rollershutter_W_WZ.
04:36:32.992 [TRACE] [.internal.VeluxGenericBindingProvider] - getConfigForItemName(velux_rollershutter_B) called.
04:36:32.996 [TRACE] [.internal.VeluxGenericBindingProvider] - getConfigForItemName() returns 53:2A:5D:5A:13:05:0A:54.
04:36:33.002 [TRACE] [.binding.velux.internal.VeluxItemType] - isToBeRefreshed() returns true.
04:36:33.005 [TRACE] [.binding.velux.internal.VeluxItemType] - getRefreshDivider() returns 20.
04:36:33.011 [TRACE] [b.binding.velux.internal.VeluxBinding] - execute(): refresh cycle not yet come for item velux_rollershutter_B.
04:36:33.015 [TRACE] [.internal.VeluxGenericBindingProvider] - getConfigForItemName(velux_bridge_products) called.
04:36:33.019 [TRACE] [.internal.VeluxGenericBindingProvider] - getConfigForItemName() returns thing=bridge;channel=products.
04:36:33.023 [TRACE] [.binding.velux.internal.VeluxItemType] - isToBeRefreshed() returns true.
04:36:33.026 [TRACE] [.binding.velux.internal.VeluxItemType] - getRefreshDivider() returns 15.
04:36:33.030 [TRACE] [b.binding.velux.internal.VeluxBinding] - execute(): refresh cycle not yet come for item velux_bridge_products.
04:36:33.033 [TRACE] [.internal.VeluxGenericBindingProvider] - getConfigForItemName(velux_rollershutter_K) called.
04:36:33.037 [TRACE] [.internal.VeluxGenericBindingProvider] - getConfigForItemName() returns 53:2A:5D:5A:13:05:16:13.
04:36:33.041 [TRACE] [.binding.velux.internal.VeluxItemType] - isToBeRefreshed() returns true.
04:36:33.045 [TRACE] [.binding.velux.internal.VeluxItemType] - getRefreshDivider() returns 20.
04:36:33.049 [TRACE] [b.binding.velux.internal.VeluxBinding] - execute(): refresh cycle not yet come for item velux_rollershutter_K.
04:36:33.053 [TRACE] [.internal.VeluxGenericBindingProvider] - getConfigForItemName(velux_rollershutter_FZ) called.
04:36:33.056 [TRACE] [.internal.VeluxGenericBindingProvider] - getConfigForItemName() returns 53:2A:5D:5A:13:05:15:30.
04:36:33.060 [TRACE] [.binding.velux.internal.VeluxItemType] - isToBeRefreshed() returns true.
04:36:33.064 [TRACE] [.binding.velux.internal.VeluxItemType] - getRefreshDivider() returns 20.
04:36:33.069 [TRACE] [b.binding.velux.internal.VeluxBinding] - execute(): refresh cycle not yet come for item velux_rollershutter_FZ.
04:36:33.073 [TRACE] [.internal.VeluxGenericBindingProvider] - getConfigForItemName(velux_bridge_status) called.
04:36:33.077 [TRACE] [.internal.VeluxGenericBindingProvider] - getConfigForItemName() returns thing=bridge;channel=status.
04:36:33.080 [TRACE] [.binding.velux.internal.VeluxItemType] - isToBeRefreshed() returns true.
04:36:33.084 [TRACE] [.binding.velux.internal.VeluxItemType] - getRefreshDivider() returns 1.
04:36:33.088 [TRACE] [b.binding.velux.internal.VeluxBinding] - execute(): refreshing item velux_bridge_status.
04:36:33.091 [DEBUG] [g.velux.handler.VeluxBridgeHandlerOH1] - handleCommandOnChannel(item=velux_bridge_status,command=null,config=org.openhab.binding.velux.internal.VeluxBindingConfig@1e7212e,provider=org.openhab.binding.velux.internal.VeluxGenericBindingProvider@1c68eaa) called.
04:36:33.098 [TRACE] [ing.velux.bridge.VeluxBridgeActuators] - updateOH() called.
04:36:33.102 [TRACE] [ng.velux.things.VeluxExistingProducts] - isDirty() returns false.
04:36:33.105 [TRACE] [ing.velux.bridge.VeluxBridgeActuators] - updateOH() finished.
04:36:33.109 [TRACE] [g.velux.handler.VeluxBridgeHandlerOH1] - handleCommandOnChannel(): openHAB items updated.
04:36:33.112 [TRACE] [g.velux.handler.VeluxBridgeHandlerOH1] - handleCommandOnChannel(): work on refresh.
04:36:33.117 [TRACE] [.internal.VeluxGenericBindingProvider] - getConfigForItemName(velux_bridge_status) called.
04:36:33.120 [TRACE] [.internal.VeluxGenericBindingProvider] - getConfigForItemName() returns thing=bridge;channel=status.
04:36:33.124 [TRACE] [.binding.velux.internal.VeluxItemType] - isReadable() returns true.
04:36:33.128 [TRACE] [g.velux.handler.VeluxBridgeHandlerOH1] - handleCommandOnChannel(): refreshing item velux_bridge_status.
04:36:33.132 [TRACE] [.velux.bridge.VeluxBridgeDeviceStatus] - VeluxBridgeDeviceStatus(constructor) called.
04:36:33.137 [TRACE] [.velux.bridge.VeluxBridgeDeviceStatus] - retrieve() called. About to query device status.
04:36:33.141 [TRACE] [ing.velux.bridge.slip.SlipVeluxBridge] - bridgeAPI() called.
04:36:33.145 [TRACE] [nhab.binding.velux.bridge.VeluxBridge] - bridgeCommunicate(Get Bridge Device Status) called.
04:36:33.149 [TRACE] [nhab.binding.velux.bridge.VeluxBridge] - isAuthenticated() returns true.
04:36:33.153 [TRACE] [nhab.binding.velux.bridge.VeluxBridge] - bridgeCommunicate(Get Bridge Device Status,authenticated) called.
04:36:33.158 [TRACE] [nhab.binding.velux.bridge.VeluxBridge] - isAuthenticated() returns true.
04:36:33.161 [TRACE] [ing.velux.bridge.slip.SlipVeluxBridge] - bridgeAPI() called.
04:36:33.165 [TRACE] [nding.velux.bridge.slip.SlipBridgeAPI] - bridgeDirectCommunicate(org.openhab.binding.velux.bridge.slip.SCgetDeviceStatus@1480507,true) called.
04:36:33.169 [TRACE] [ing.velux.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate(Get Bridge Device Status,authenticated) called.
04:36:33.172 [DEBUG] [g.velux.bridge.slip.SCgetDeviceStatus] - getRequestCommand() returns GW_GET_STATE_REQ (0xc).
04:36:33.177 [TRACE] [g.velux.bridge.slip.SCgetDeviceStatus] - getRequestDataAsArrayOfBytes() returns data.
04:36:33.181 [DEBUG] [ing.velux.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate(GW_GET_STATE_REQ,authenticated) called.
04:36:33.184 [DEBUG] [ing.velux.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate(): working on request GW_GET_STATE_REQ with 0 bytes of data.
04:36:33.188 [TRACE] [g.velux.bridge.slip.util.SlipEncoding] - SlipEncoding(constructor) for command 0xc with data size 0 called.
04:36:33.192 [TRACE] [g.velux.bridge.slip.util.SlipEncoding] - SlipEncoding(constructor) successfully initialized, storing bytes: 00 03 00 0C 0F.
04:36:33.198 [TRACE] [ing.velux.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate(): transportEncoding=00 03 00 0C 0F.
04:36:33.201 [TRACE] [ng.velux.bridge.slip.util.SlipRFC1055] - encode() for data size 5 called.
04:36:33.206 [TRACE] [ng.velux.bridge.slip.util.SlipRFC1055] - encode() provides transfer encoding: C0 00 03 00 0C 0F C0.
04:36:33.211 [TRACE] [ing.velux.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate(): sending 7 bytes to 192.168.178.22:51200.
04:36:33.214 [TRACE] [ing.velux.bridge.slip.SlipVeluxBridge] - io(192.168.178.22,51200,7 bytes) called.
04:36:33.219 [TRACE] [ing.velux.bridge.slip.SlipVeluxBridge] - io(): sending packet with 7 bytes: C0 00 03 00 0C 0F C0
04:36:33.222 [TRACE] [ng.velux.bridge.slip.io.SSLconnection] - send() called, writing 7 bytes.
04:36:33.227 [WARN ] [ing.velux.bridge.slip.SlipVeluxBridge] - io(): raised an error during sending: Connection closed by remote host.
04:36:33.233 [TRACE] [ing.velux.bridge.slip.SlipVeluxBridge] - io(): wait time 2000 msecs.
04:36:35.238 [TRACE] [ing.velux.bridge.slip.SlipVeluxBridge] - io(): receiving bytes.
04:36:35.246 [TRACE] [ng.velux.bridge.slip.io.SSLconnection] - receive() called.
04:36:35.252 [WARN ] [ing.velux.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate(): method io() raised an error: null.
04:36:35.257 [DEBUG] [ing.velux.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate(GW_GET_STATE_REQ) returns failure.
04:36:35.264 [TRACE] [.velux.bridge.VeluxBridgeDeviceStatus] - retrieve() finished with failure.
04:36:35.270 [INFO ] [g.velux.handler.VeluxBridgeHandlerOH1] - handleCommandOnChannel(): updating of item velux_bridge_status (type BRIDGE_STATUS) failed.
04:36:35.280 [TRACE] [g.velux.handler.VeluxBridgeHandlerOH1] - handleCommandOnChannel() done.
04:36:35.286 [TRACE] [.internal.VeluxGenericBindingProvider] - getConfigForItemName(velux_rollershutter_SZ) called.
04:36:35.292 [TRACE] [.internal.VeluxGenericBindingProvider] - getConfigForItemName() returns 53:2A:5D:5A:13:05:15:18.
04:36:35.297 [TRACE] [.binding.velux.internal.VeluxItemType] - isToBeRefreshed() returns true.
04:36:35.301 [TRACE] [.binding.velux.internal.VeluxItemType] - getRefreshDivider() returns 20.
04:36:35.306 [TRACE] [b.binding.velux.internal.VeluxBinding] - execute(): refresh cycle not yet come for item velux_rollershutter_SZ.
04:36:35.310 [TRACE] [.internal.VeluxGenericBindingProvider] - getConfigForItemName(velux_bridge_check) called.
04:36:35.314 [TRACE] [.internal.VeluxGenericBindingProvider] - getConfigForItemName() returns thing=bridge;channel=check.
04:36:35.318 [TRACE] [.binding.velux.internal.VeluxItemType] - isToBeRefreshed() returns true.
04:36:35.321 [TRACE] [.binding.velux.internal.VeluxItemType] - getRefreshDivider() returns 5760.
04:36:35.326 [TRACE] [b.binding.velux.internal.VeluxBinding] - execute(): refresh cycle not yet come for item velux_bridge_check.
04:36:35.330 [TRACE] [.internal.VeluxGenericBindingProvider] - getConfigForItemName(velux_bridge_scenes) called.
04:36:35.335 [TRACE] [.internal.VeluxGenericBindingProvider] - getConfigForItemName() returns thing=bridge;channel=scenes.
04:36:35.338 [TRACE] [.binding.velux.internal.VeluxItemType] - isToBeRefreshed() returns true.
04:36:35.342 [TRACE] [.binding.velux.internal.VeluxItemType] - getRefreshDivider() returns 5760.
04:36:35.347 [TRACE] [b.binding.velux.internal.VeluxBinding] - execute(): refresh cycle not yet come for item velux_bridge_scenes.
04:36:35.351 [TRACE] [.internal.VeluxGenericBindingProvider] - getConfigForItemName(velux_rollershutter_O_WZ) called.
04:36:35.356 [TRACE] [.internal.VeluxGenericBindingProvider] - getConfigForItemName() returns 53:2A:5D:5A:13:05:15:1F.
04:36:35.360 [TRACE] [.binding.velux.internal.VeluxItemType] - isToBeRefreshed() returns true.
04:36:35.364 [TRACE] [.binding.velux.internal.VeluxItemType] - getRefreshDivider() returns 20.
04:36:35.368 [TRACE] [b.binding.velux.internal.VeluxBinding] - execute(): refresh cycle not yet come for item velux_rollershutter_O_WZ.
04:36:35.375 [DEBUG] [b.binding.velux.internal.VeluxBinding] - execute() done.