tail -f /var/log/openhab/openhab.log /var/log/openhab/events.log
velux
2023-09-05 15:02:44.360 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:02:44.361 [TRACE] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate(BCP: Retrieve House Status, authenticated) called.
2023-09-05 15:02:44.362 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,products) returns enum velux:klf200/products.
2023-09-05 15:02:44.364 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:02:44.364 [TRACE] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate() 'Retrieve House Status', authenticated
2023-09-05 15:02:44.366 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,scenes) returns enum velux:klf200/scenes.
2023-09-05 15:02:44.367 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:02:44.369 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,check) returns enum velux:klf200/check.
2023-09-05 15:02:44.371 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:02:44.373 [DEBUG] [.internal.handler.VeluxBridgeHandler] - refreshSchedulerJob() initiated by Thread[OH-thingHandler-8452,5,main] finished cycle 18013.
2023-09-05 15:02:44.373 [DEBUG] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate() [192.168.170.13] GW_OPENHAB_RECEIVEONLY => started => Thread[OH-velux:klf200:192_168_170_89-1,5,main] 
2023-09-05 15:02:44.377 [TRACE] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate() [192.168.170.13] GW_OPENHAB_RECEIVEONLY => receive-only mode => checking messages 
2023-09-05 15:02:44.392 [TRACE] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate() [192.168.170.13] GW_OPENHAB_RECEIVEONLY => message(s) waiting => start reading 
2023-09-05 15:02:44.432 [TRACE] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate() [192.168.170.13] GW_OPENHAB_RECEIVEONLY => GW_SESSION_FINISHED_NTF => received data => 0F C3
2023-09-05 15:02:44.434 [TRACE] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate() [192.168.170.13] GW_OPENHAB_RECEIVEONLY => GW_SESSION_FINISHED_NTF => parallelism allowed => continuing
2023-09-05 15:02:45.737 [DEBUG] [.internal.handler.VeluxBridgeHandler] - refreshSchedulerJob() initiated by Thread[OH-thingHandler-8453,5,main] starting cycle 18014.
2023-09-05 15:02:45.738 [TRACE] [.internal.handler.VeluxBridgeHandler] - refreshSchedulerJob(): processing of possible HSM messages.
2023-09-05 15:02:45.740 [TRACE] [.internal.handler.VeluxBridgeHandler] - refreshSchedulerJob(): loop through all (child things and bridge) linked channels needing a refresh
2023-09-05 15:02:45.741 [TRACE] [elux.internal.handler.BridgeChannels] - getAllLinkedChannelUIDs() returns [velux:rollershutter:192_168_170_89:R_D07_2:position, velux:rollershutter:192_168_170_89:_0:limitMaximum, velux:rollershutter:192_168_170_89:_0:position].
2023-09-05 15:02:45.742 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:rollershutter,position) returns enum velux:rollershutter/position.
2023-09-05 15:02:45.743 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:02:45.745 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:rollershutter,limitMaximum) returns enum velux:rollershutter/limitMaximum.
2023-09-05 15:02:45.746 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:02:45.747 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:rollershutter,position) returns enum velux:rollershutter/position.
2023-09-05 15:02:45.748 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:02:45.749 [TRACE] [.internal.handler.VeluxBridgeHandler] - refreshSchedulerJob(): loop through properties needing a refresh
2023-09-05 15:02:45.751 [TRACE] [binding.velux.internal.VeluxItemType] - getPropertyEntriesByThing(velux:klf200) returns [velux:klf200/firmware, velux:klf200/address, velux:klf200/subnetMask, velux:klf200/defaultGW, velux:klf200/DHCP, velux:klf200/WLANSSID, velux:klf200/WLANPassword, velux:klf200/products, velux:klf200/scenes, velux:klf200/check].
2023-09-05 15:02:45.752 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,firmware) returns enum velux:klf200/firmware.
2023-09-05 15:02:45.753 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:02:45.754 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,address) returns enum velux:klf200/address.
2023-09-05 15:02:45.755 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:02:45.756 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,subnetMask) returns enum velux:klf200/subnetMask.
2023-09-05 15:02:45.758 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:02:45.759 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,defaultGW) returns enum velux:klf200/defaultGW.
2023-09-05 15:02:45.760 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:02:45.761 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,DHCP) returns enum velux:klf200/DHCP.
2023-09-05 15:02:45.762 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:02:45.764 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,WLANSSID) returns enum velux:klf200/WLANSSID.
2023-09-05 15:02:45.765 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:02:45.766 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,WLANPassword) returns enum velux:klf200/WLANPassword.
2023-09-05 15:02:45.767 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:02:45.768 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,products) returns enum velux:klf200/products.
2023-09-05 15:02:45.769 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:02:45.770 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,scenes) returns enum velux:klf200/scenes.
2023-09-05 15:02:45.772 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:02:45.774 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,check) returns enum velux:klf200/check.
2023-09-05 15:02:45.775 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:02:45.776 [DEBUG] [.internal.handler.VeluxBridgeHandler] - refreshSchedulerJob() initiated by Thread[OH-thingHandler-8453,5,main] finished cycle 18014.
2023-09-05 15:02:47.568 [DEBUG] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate() [192.168.170.13] GW_OPENHAB_RECEIVEONLY => finished => success
2023-09-05 15:02:47.569 [DEBUG] [nal.bridge.VeluxBridgeGetHouseStatus] - evaluateState() finished with failure.
2023-09-05 15:02:47.571 [TRACE] [.internal.handler.VeluxBridgeHandler] - getHouseStatusCommsJob(): => GetHouseStatus() => no updates
2023-09-05 15:02:47.572 [TRACE] [.internal.handler.VeluxBridgeHandler] - getHouseStatusCommsJob() initiated by Thread[OH-velux:klf200:192_168_170_89-1,5,main] has finished.
2023-09-05 15:02:47.574 [TRACE] [.internal.handler.VeluxBridgeHandler] - getHouseStatusCommsJob() initiated by Thread[OH-velux:klf200:192_168_170_89-1,5,main] will process HouseStatus.
2023-09-05 15:02:47.576 [TRACE] [nal.bridge.VeluxBridgeGetHouseStatus] - evaluateState() called.
2023-09-05 15:02:47.577 [TRACE] [internal.bridge.slip.SlipVeluxBridge] - bridgeAPI() called.
2023-09-05 15:02:47.579 [TRACE] [ng.velux.internal.bridge.VeluxBridge] - bridgeCommunicate(Retrieve House Status) called.
2023-09-05 15:02:47.581 [TRACE] [ng.velux.internal.bridge.VeluxBridge] - isAuthenticated() returns true.
2023-09-05 15:02:47.583 [TRACE] [ng.velux.internal.bridge.VeluxBridge] - bridgeCommunicate(Retrieve House Status,authenticated) called.
2023-09-05 15:02:47.585 [TRACE] [ng.velux.internal.bridge.VeluxBridge] - isAuthenticated() returns true.
2023-09-05 15:02:47.587 [TRACE] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate(BCP: Retrieve House Status, authenticated) called.
2023-09-05 15:02:47.588 [TRACE] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate() 'Retrieve House Status', authenticated
2023-09-05 15:02:47.591 [DEBUG] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate() [192.168.170.13] GW_OPENHAB_RECEIVEONLY => started => Thread[OH-velux:klf200:192_168_170_89-1,5,main] 
2023-09-05 15:02:47.592 [TRACE] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate() [192.168.170.13] GW_OPENHAB_RECEIVEONLY => receive-only mode => checking messages 
2023-09-05 15:02:47.604 [TRACE] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate() [192.168.170.13] GW_OPENHAB_RECEIVEONLY => no waiting messages => done 
2023-09-05 15:02:47.606 [DEBUG] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate() [192.168.170.13] GW_OPENHAB_RECEIVEONLY => finished => success
2023-09-05 15:02:47.608 [DEBUG] [nal.bridge.VeluxBridgeGetHouseStatus] - evaluateState() finished with failure.
2023-09-05 15:02:47.609 [TRACE] [.internal.handler.VeluxBridgeHandler] - getHouseStatusCommsJob(): => GetHouseStatus() => no updates
2023-09-05 15:02:47.612 [TRACE] [.internal.handler.VeluxBridgeHandler] - getHouseStatusCommsJob() initiated by Thread[OH-velux:klf200:192_168_170_89-1,5,main] has finished.
2023-09-05 15:02:54.375 [DEBUG] [.internal.handler.VeluxBridgeHandler] - refreshSchedulerJob() initiated by Thread[OH-thingHandler-8452,5,main] starting cycle 18015.
2023-09-05 15:02:54.376 [TRACE] [.internal.handler.VeluxBridgeHandler] - refreshSchedulerJob(): processing of possible HSM messages.
2023-09-05 15:02:54.377 [TRACE] [.internal.handler.VeluxBridgeHandler] - refreshSchedulerJob(): loop through all (child things and bridge) linked channels needing a refresh
2023-09-05 15:02:54.380 [TRACE] [.internal.handler.VeluxBridgeHandler] - getHouseStatusCommsJob() initiated by Thread[OH-velux:klf200:192_168_170_89-1,5,main] will process HouseStatus.
2023-09-05 15:02:54.381 [TRACE] [nal.bridge.VeluxBridgeGetHouseStatus] - evaluateState() called.
2023-09-05 15:02:54.382 [TRACE] [elux.internal.handler.BridgeChannels] - getAllLinkedChannelUIDs() returns [velux:rollershutter:192_168_170_89:R_D07_2:position, velux:rollershutter:192_168_170_89:_0:limitMaximum, velux:rollershutter:192_168_170_89:_0:position].
2023-09-05 15:02:54.383 [TRACE] [internal.bridge.slip.SlipVeluxBridge] - bridgeAPI() called.
2023-09-05 15:02:54.384 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:rollershutter,position) returns enum velux:rollershutter/position.
2023-09-05 15:02:54.384 [TRACE] [ng.velux.internal.bridge.VeluxBridge] - bridgeCommunicate(Retrieve House Status) called.
2023-09-05 15:02:54.385 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:02:54.385 [TRACE] [ng.velux.internal.bridge.VeluxBridge] - isAuthenticated() returns true.
2023-09-05 15:02:54.386 [TRACE] [ng.velux.internal.bridge.VeluxBridge] - bridgeCommunicate(Retrieve House Status,authenticated) called.
2023-09-05 15:02:54.386 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:rollershutter,limitMaximum) returns enum velux:rollershutter/limitMaximum.
2023-09-05 15:02:54.387 [TRACE] [ng.velux.internal.bridge.VeluxBridge] - isAuthenticated() returns true.
2023-09-05 15:02:54.388 [TRACE] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate(BCP: Retrieve House Status, authenticated) called.
2023-09-05 15:02:54.388 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:02:54.388 [TRACE] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate() 'Retrieve House Status', authenticated
2023-09-05 15:02:54.389 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:rollershutter,position) returns enum velux:rollershutter/position.
2023-09-05 15:02:54.389 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:02:54.390 [DEBUG] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate() [192.168.170.13] GW_OPENHAB_RECEIVEONLY => started => Thread[OH-velux:klf200:192_168_170_89-1,5,main] 
2023-09-05 15:02:54.390 [TRACE] [.internal.handler.VeluxBridgeHandler] - refreshSchedulerJob(): loop through properties needing a refresh
2023-09-05 15:02:54.391 [TRACE] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate() [192.168.170.13] GW_OPENHAB_RECEIVEONLY => receive-only mode => checking messages 
2023-09-05 15:02:54.391 [TRACE] [binding.velux.internal.VeluxItemType] - getPropertyEntriesByThing(velux:klf200) returns [velux:klf200/firmware, velux:klf200/address, velux:klf200/subnetMask, velux:klf200/defaultGW, velux:klf200/DHCP, velux:klf200/WLANSSID, velux:klf200/WLANPassword, velux:klf200/products, velux:klf200/scenes, velux:klf200/check].
2023-09-05 15:02:54.393 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,firmware) returns enum velux:klf200/firmware.
2023-09-05 15:02:54.394 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:02:54.396 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,address) returns enum velux:klf200/address.
2023-09-05 15:02:54.397 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:02:54.400 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,subnetMask) returns enum velux:klf200/subnetMask.
2023-09-05 15:02:54.401 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:02:54.403 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,defaultGW) returns enum velux:klf200/defaultGW.
2023-09-05 15:02:54.404 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:02:54.406 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,DHCP) returns enum velux:klf200/DHCP.
2023-09-05 15:02:54.407 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:02:54.409 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,WLANSSID) returns enum velux:klf200/WLANSSID.
2023-09-05 15:02:54.411 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:02:54.412 [TRACE] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate() [192.168.170.13] GW_OPENHAB_RECEIVEONLY => no waiting messages => done 
2023-09-05 15:02:54.412 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,WLANPassword) returns enum velux:klf200/WLANPassword.
2023-09-05 15:02:54.413 [DEBUG] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate() [192.168.170.13] GW_OPENHAB_RECEIVEONLY => finished => success
2023-09-05 15:02:54.413 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:02:54.414 [DEBUG] [nal.bridge.VeluxBridgeGetHouseStatus] - evaluateState() finished with failure.
2023-09-05 15:02:54.415 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,products) returns enum velux:klf200/products.
2023-09-05 15:02:54.416 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:02:54.418 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,scenes) returns enum velux:klf200/scenes.
2023-09-05 15:02:54.420 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:02:54.421 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,check) returns enum velux:klf200/check.
2023-09-05 15:02:54.415 [TRACE] [.internal.handler.VeluxBridgeHandler] - getHouseStatusCommsJob(): => GetHouseStatus() => no updates
2023-09-05 15:02:54.423 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:02:54.424 [DEBUG] [.internal.handler.VeluxBridgeHandler] - refreshSchedulerJob() initiated by Thread[OH-thingHandler-8452,5,main] finished cycle 18015.
2023-09-05 15:02:54.426 [TRACE] [.internal.handler.VeluxBridgeHandler] - getHouseStatusCommsJob() initiated by Thread[OH-velux:klf200:192_168_170_89-1,5,main] has finished.
2023-09-05 15:02:55.777 [DEBUG] [.internal.handler.VeluxBridgeHandler] - refreshSchedulerJob() initiated by Thread[OH-thingHandler-8453,5,main] starting cycle 18016.
2023-09-05 15:02:55.779 [TRACE] [.internal.handler.VeluxBridgeHandler] - refreshSchedulerJob(): processing of possible HSM messages.
2023-09-05 15:02:55.780 [TRACE] [.internal.handler.VeluxBridgeHandler] - refreshSchedulerJob(): loop through all (child things and bridge) linked channels needing a refresh
2023-09-05 15:02:55.780 [TRACE] [.internal.handler.VeluxBridgeHandler] - getHouseStatusCommsJob() initiated by Thread[OH-velux:klf200:192_168_170_89-1,5,main] will process HouseStatus.
2023-09-05 15:02:55.781 [TRACE] [elux.internal.handler.BridgeChannels] - getAllLinkedChannelUIDs() returns [velux:rollershutter:192_168_170_89:R_D07_2:position, velux:rollershutter:192_168_170_89:_0:limitMaximum, velux:rollershutter:192_168_170_89:_0:position].
2023-09-05 15:02:55.781 [TRACE] [nal.bridge.VeluxBridgeGetHouseStatus] - evaluateState() called.
2023-09-05 15:02:55.782 [TRACE] [internal.bridge.slip.SlipVeluxBridge] - bridgeAPI() called.
2023-09-05 15:02:55.782 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:rollershutter,position) returns enum velux:rollershutter/position.
2023-09-05 15:02:55.783 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:02:55.783 [TRACE] [ng.velux.internal.bridge.VeluxBridge] - bridgeCommunicate(Retrieve House Status) called.
2023-09-05 15:02:55.784 [TRACE] [ng.velux.internal.bridge.VeluxBridge] - isAuthenticated() returns true.
2023-09-05 15:02:55.784 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:rollershutter,limitMaximum) returns enum velux:rollershutter/limitMaximum.
2023-09-05 15:02:55.785 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:02:55.785 [TRACE] [ng.velux.internal.bridge.VeluxBridge] - bridgeCommunicate(Retrieve House Status,authenticated) called.
2023-09-05 15:02:55.785 [TRACE] [ng.velux.internal.bridge.VeluxBridge] - isAuthenticated() returns true.
2023-09-05 15:02:55.786 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:rollershutter,position) returns enum velux:rollershutter/position.
2023-09-05 15:02:55.786 [TRACE] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate(BCP: Retrieve House Status, authenticated) called.
2023-09-05 15:02:55.786 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:02:55.787 [TRACE] [.internal.handler.VeluxBridgeHandler] - refreshSchedulerJob(): loop through properties needing a refresh
2023-09-05 15:02:55.787 [TRACE] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate() 'Retrieve House Status', authenticated
2023-09-05 15:02:55.789 [TRACE] [binding.velux.internal.VeluxItemType] - getPropertyEntriesByThing(velux:klf200) returns [velux:klf200/firmware, velux:klf200/address, velux:klf200/subnetMask, velux:klf200/defaultGW, velux:klf200/DHCP, velux:klf200/WLANSSID, velux:klf200/WLANPassword, velux:klf200/products, velux:klf200/scenes, velux:klf200/check].
2023-09-05 15:02:55.790 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,firmware) returns enum velux:klf200/firmware.
2023-09-05 15:02:55.791 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:02:55.792 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,address) returns enum velux:klf200/address.
2023-09-05 15:02:55.793 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:02:55.793 [DEBUG] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate() [192.168.170.13] GW_OPENHAB_RECEIVEONLY => started => Thread[OH-velux:klf200:192_168_170_89-1,5,main] 
2023-09-05 15:02:55.795 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,subnetMask) returns enum velux:klf200/subnetMask.
2023-09-05 15:02:55.795 [TRACE] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate() [192.168.170.13] GW_OPENHAB_RECEIVEONLY => receive-only mode => checking messages 
2023-09-05 15:02:55.796 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:02:55.797 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,defaultGW) returns enum velux:klf200/defaultGW.
2023-09-05 15:02:55.799 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:02:55.800 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,DHCP) returns enum velux:klf200/DHCP.
2023-09-05 15:02:55.801 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:02:55.802 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,WLANSSID) returns enum velux:klf200/WLANSSID.
2023-09-05 15:02:55.803 [TRACE] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate() [192.168.170.13] GW_OPENHAB_RECEIVEONLY => no waiting messages => done 
2023-09-05 15:02:55.804 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:02:55.805 [DEBUG] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate() [192.168.170.13] GW_OPENHAB_RECEIVEONLY => finished => success
2023-09-05 15:02:55.805 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,WLANPassword) returns enum velux:klf200/WLANPassword.
2023-09-05 15:02:55.806 [DEBUG] [nal.bridge.VeluxBridgeGetHouseStatus] - evaluateState() finished with failure.
2023-09-05 15:02:55.806 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:02:55.807 [TRACE] [.internal.handler.VeluxBridgeHandler] - getHouseStatusCommsJob(): => GetHouseStatus() => no updates
2023-09-05 15:02:55.807 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,products) returns enum velux:klf200/products.
2023-09-05 15:02:55.808 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:02:55.808 [TRACE] [.internal.handler.VeluxBridgeHandler] - getHouseStatusCommsJob() initiated by Thread[OH-velux:klf200:192_168_170_89-1,5,main] has finished.
2023-09-05 15:02:55.809 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,scenes) returns enum velux:klf200/scenes.
2023-09-05 15:02:55.810 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:02:55.811 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,check) returns enum velux:klf200/check.
2023-09-05 15:02:55.812 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:02:55.813 [DEBUG] [.internal.handler.VeluxBridgeHandler] - refreshSchedulerJob() initiated by Thread[OH-thingHandler-8453,5,main] finished cycle 18016.
2023-09-05 15:03:04.426 [DEBUG] [.internal.handler.VeluxBridgeHandler] - refreshSchedulerJob() initiated by Thread[OH-thingHandler-8454,5,main] starting cycle 18017.
2023-09-05 15:03:04.427 [TRACE] [.internal.handler.VeluxBridgeHandler] - refreshSchedulerJob(): processing of possible HSM messages.
2023-09-05 15:03:04.428 [TRACE] [.internal.handler.VeluxBridgeHandler] - refreshSchedulerJob(): loop through all (child things and bridge) linked channels needing a refresh
2023-09-05 15:03:04.428 [TRACE] [.internal.handler.VeluxBridgeHandler] - getHouseStatusCommsJob() initiated by Thread[OH-velux:klf200:192_168_170_89-1,5,main] will process HouseStatus.
2023-09-05 15:03:04.429 [TRACE] [nal.bridge.VeluxBridgeGetHouseStatus] - evaluateState() called.
2023-09-05 15:03:04.429 [TRACE] [elux.internal.handler.BridgeChannels] - getAllLinkedChannelUIDs() returns [velux:rollershutter:192_168_170_89:R_D07_2:position, velux:rollershutter:192_168_170_89:_0:limitMaximum, velux:rollershutter:192_168_170_89:_0:position].
2023-09-05 15:03:04.430 [TRACE] [internal.bridge.slip.SlipVeluxBridge] - bridgeAPI() called.
2023-09-05 15:03:04.431 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:rollershutter,position) returns enum velux:rollershutter/position.
2023-09-05 15:03:04.432 [TRACE] [ng.velux.internal.bridge.VeluxBridge] - bridgeCommunicate(Retrieve House Status) called.
2023-09-05 15:03:04.432 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:04.433 [TRACE] [ng.velux.internal.bridge.VeluxBridge] - isAuthenticated() returns true.
2023-09-05 15:03:04.434 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:rollershutter,limitMaximum) returns enum velux:rollershutter/limitMaximum.
2023-09-05 15:03:04.434 [TRACE] [ng.velux.internal.bridge.VeluxBridge] - bridgeCommunicate(Retrieve House Status,authenticated) called.
2023-09-05 15:03:04.435 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:04.436 [TRACE] [ng.velux.internal.bridge.VeluxBridge] - isAuthenticated() returns true.
2023-09-05 15:03:04.437 [TRACE] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate(BCP: Retrieve House Status, authenticated) called.
2023-09-05 15:03:04.437 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:rollershutter,position) returns enum velux:rollershutter/position.
2023-09-05 15:03:04.438 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:04.438 [TRACE] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate() 'Retrieve House Status', authenticated
2023-09-05 15:03:04.440 [TRACE] [.internal.handler.VeluxBridgeHandler] - refreshSchedulerJob(): loop through properties needing a refresh
2023-09-05 15:03:04.441 [DEBUG] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate() [192.168.170.13] GW_OPENHAB_RECEIVEONLY => started => Thread[OH-velux:klf200:192_168_170_89-1,5,main] 
2023-09-05 15:03:04.441 [TRACE] [binding.velux.internal.VeluxItemType] - getPropertyEntriesByThing(velux:klf200) returns [velux:klf200/firmware, velux:klf200/address, velux:klf200/subnetMask, velux:klf200/defaultGW, velux:klf200/DHCP, velux:klf200/WLANSSID, velux:klf200/WLANPassword, velux:klf200/products, velux:klf200/scenes, velux:klf200/check].
2023-09-05 15:03:04.442 [TRACE] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate() [192.168.170.13] GW_OPENHAB_RECEIVEONLY => receive-only mode => checking messages 
2023-09-05 15:03:04.443 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,firmware) returns enum velux:klf200/firmware.
2023-09-05 15:03:04.444 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:04.446 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,address) returns enum velux:klf200/address.
2023-09-05 15:03:04.447 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:04.449 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,subnetMask) returns enum velux:klf200/subnetMask.
2023-09-05 15:03:04.450 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:04.452 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,defaultGW) returns enum velux:klf200/defaultGW.
2023-09-05 15:03:04.453 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:04.453 [TRACE] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate() [192.168.170.13] GW_OPENHAB_RECEIVEONLY => no waiting messages => done 
2023-09-05 15:03:04.455 [DEBUG] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate() [192.168.170.13] GW_OPENHAB_RECEIVEONLY => finished => success
2023-09-05 15:03:04.455 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,DHCP) returns enum velux:klf200/DHCP.
2023-09-05 15:03:04.456 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:04.456 [DEBUG] [nal.bridge.VeluxBridgeGetHouseStatus] - evaluateState() finished with failure.
2023-09-05 15:03:04.458 [TRACE] [.internal.handler.VeluxBridgeHandler] - getHouseStatusCommsJob(): => GetHouseStatus() => no updates
2023-09-05 15:03:04.458 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,WLANSSID) returns enum velux:klf200/WLANSSID.
2023-09-05 15:03:04.459 [TRACE] [.internal.handler.VeluxBridgeHandler] - getHouseStatusCommsJob() initiated by Thread[OH-velux:klf200:192_168_170_89-1,5,main] has finished.
2023-09-05 15:03:04.459 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:04.461 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,WLANPassword) returns enum velux:klf200/WLANPassword.
2023-09-05 15:03:04.462 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:04.464 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,products) returns enum velux:klf200/products.
2023-09-05 15:03:04.465 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:04.466 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,scenes) returns enum velux:klf200/scenes.
2023-09-05 15:03:04.467 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:04.469 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,check) returns enum velux:klf200/check.
2023-09-05 15:03:04.470 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:04.471 [DEBUG] [.internal.handler.VeluxBridgeHandler] - refreshSchedulerJob() initiated by Thread[OH-thingHandler-8454,5,main] finished cycle 18017.
2023-09-05 15:03:05.815 [DEBUG] [.internal.handler.VeluxBridgeHandler] - refreshSchedulerJob() initiated by Thread[OH-thingHandler-8452,5,main] starting cycle 18018.
2023-09-05 15:03:05.816 [TRACE] [.internal.handler.VeluxBridgeHandler] - refreshSchedulerJob(): processing of possible HSM messages.
2023-09-05 15:03:05.817 [TRACE] [.internal.handler.VeluxBridgeHandler] - refreshSchedulerJob(): loop through all (child things and bridge) linked channels needing a refresh
2023-09-05 15:03:05.818 [TRACE] [.internal.handler.VeluxBridgeHandler] - getHouseStatusCommsJob() initiated by Thread[OH-velux:klf200:192_168_170_89-1,5,main] will process HouseStatus.
2023-09-05 15:03:05.818 [TRACE] [elux.internal.handler.BridgeChannels] - getAllLinkedChannelUIDs() returns [velux:rollershutter:192_168_170_89:R_D07_2:position, velux:rollershutter:192_168_170_89:_0:limitMaximum, velux:rollershutter:192_168_170_89:_0:position].
2023-09-05 15:03:05.819 [TRACE] [nal.bridge.VeluxBridgeGetHouseStatus] - evaluateState() called.
2023-09-05 15:03:05.819 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:rollershutter,position) returns enum velux:rollershutter/position.
2023-09-05 15:03:05.820 [TRACE] [internal.bridge.slip.SlipVeluxBridge] - bridgeAPI() called.
2023-09-05 15:03:05.820 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning true, as item is to be refreshed, now.
2023-09-05 15:03:05.820 [TRACE] [ng.velux.internal.bridge.VeluxBridge] - bridgeCommunicate(Retrieve House Status) called.
2023-09-05 15:03:05.820 [TRACE] [.internal.handler.VeluxBridgeHandler] - refreshSchedulerJob(): refreshing channel velux:rollershutter:192_168_170_89:R_D07_2:position.
2023-09-05 15:03:05.822 [TRACE] [.internal.handler.VeluxBridgeHandler] - handleCommand(Thread[OH-thingHandler-8452,5,main]): command REFRESH on channel velux:rollershutter:192_168_170_89:R_D07_2:position will be scheduled.
2023-09-05 15:03:05.823 [DEBUG] [.internal.handler.VeluxBridgeHandler] - handleCommand(velux:rollershutter:192_168_170_89:R_D07_2:position,REFRESH) called.
2023-09-05 15:03:05.825 [TRACE] [.internal.handler.VeluxBridgeHandler] - handleCommand(Thread[OH-thingHandler-8452,5,main]) done.
2023-09-05 15:03:05.826 [TRACE] [ng.velux.internal.bridge.VeluxBridge] - isAuthenticated() returns true.
2023-09-05 15:03:05.827 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:rollershutter,limitMaximum) returns enum velux:rollershutter/limitMaximum.
2023-09-05 15:03:05.827 [TRACE] [ng.velux.internal.bridge.VeluxBridge] - bridgeCommunicate(Retrieve House Status,authenticated) called.
2023-09-05 15:03:05.828 [TRACE] [ng.velux.internal.bridge.VeluxBridge] - isAuthenticated() returns true.
2023-09-05 15:03:05.828 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning true, as item is to be refreshed, now.
2023-09-05 15:03:05.829 [TRACE] [.internal.handler.VeluxBridgeHandler] - refreshSchedulerJob(): refreshing channel velux:rollershutter:192_168_170_89:_0:limitMaximum.
2023-09-05 15:03:05.829 [TRACE] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate(BCP: Retrieve House Status, authenticated) called.
2023-09-05 15:03:05.830 [TRACE] [.internal.handler.VeluxBridgeHandler] - handleCommand(Thread[OH-thingHandler-8452,5,main]): command REFRESH on channel velux:rollershutter:192_168_170_89:_0:limitMaximum will be scheduled.
2023-09-05 15:03:05.832 [DEBUG] [.internal.handler.VeluxBridgeHandler] - handleCommand(velux:rollershutter:192_168_170_89:_0:limitMaximum,REFRESH) called.
2023-09-05 15:03:05.833 [TRACE] [.internal.handler.VeluxBridgeHandler] - handleCommand(Thread[OH-thingHandler-8452,5,main]) done.
2023-09-05 15:03:05.835 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:rollershutter,position) returns enum velux:rollershutter/position.
2023-09-05 15:03:05.835 [TRACE] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate() 'Retrieve House Status', authenticated
2023-09-05 15:03:05.836 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning true, as item is to be refreshed, now.
2023-09-05 15:03:05.838 [TRACE] [.internal.handler.VeluxBridgeHandler] - refreshSchedulerJob(): refreshing channel velux:rollershutter:192_168_170_89:_0:position.
2023-09-05 15:03:05.839 [TRACE] [.internal.handler.VeluxBridgeHandler] - handleCommand(Thread[OH-thingHandler-8452,5,main]): command REFRESH on channel velux:rollershutter:192_168_170_89:_0:position will be scheduled.
2023-09-05 15:03:05.840 [DEBUG] [.internal.handler.VeluxBridgeHandler] - handleCommand(velux:rollershutter:192_168_170_89:_0:position,REFRESH) called.
2023-09-05 15:03:05.842 [TRACE] [.internal.handler.VeluxBridgeHandler] - handleCommand(Thread[OH-thingHandler-8452,5,main]) done.
2023-09-05 15:03:05.840 [DEBUG] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate() [192.168.170.13] GW_OPENHAB_RECEIVEONLY => started => Thread[OH-velux:klf200:192_168_170_89-1,5,main] 
2023-09-05 15:03:05.843 [TRACE] [.internal.handler.VeluxBridgeHandler] - refreshSchedulerJob(): loop through properties needing a refresh
2023-09-05 15:03:05.844 [TRACE] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate() [192.168.170.13] GW_OPENHAB_RECEIVEONLY => receive-only mode => checking messages 
2023-09-05 15:03:05.844 [TRACE] [binding.velux.internal.VeluxItemType] - getPropertyEntriesByThing(velux:klf200) returns [velux:klf200/firmware, velux:klf200/address, velux:klf200/subnetMask, velux:klf200/defaultGW, velux:klf200/DHCP, velux:klf200/WLANSSID, velux:klf200/WLANPassword, velux:klf200/products, velux:klf200/scenes, velux:klf200/check].
2023-09-05 15:03:05.849 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,firmware) returns enum velux:klf200/firmware.
2023-09-05 15:03:05.851 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:05.852 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,address) returns enum velux:klf200/address.
2023-09-05 15:03:05.854 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:05.856 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,subnetMask) returns enum velux:klf200/subnetMask.
2023-09-05 15:03:05.858 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:05.859 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,defaultGW) returns enum velux:klf200/defaultGW.
2023-09-05 15:03:05.860 [TRACE] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate() [192.168.170.13] GW_OPENHAB_RECEIVEONLY => no waiting messages => done 
2023-09-05 15:03:05.861 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:05.861 [DEBUG] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate() [192.168.170.13] GW_OPENHAB_RECEIVEONLY => finished => success
2023-09-05 15:03:05.863 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,DHCP) returns enum velux:klf200/DHCP.
2023-09-05 15:03:05.863 [DEBUG] [nal.bridge.VeluxBridgeGetHouseStatus] - evaluateState() finished with failure.
2023-09-05 15:03:05.865 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:05.865 [TRACE] [.internal.handler.VeluxBridgeHandler] - getHouseStatusCommsJob(): => GetHouseStatus() => no updates
2023-09-05 15:03:05.866 [TRACE] [.internal.handler.VeluxBridgeHandler] - getHouseStatusCommsJob() initiated by Thread[OH-velux:klf200:192_168_170_89-1,5,main] has finished.
2023-09-05 15:03:05.867 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,WLANSSID) returns enum velux:klf200/WLANSSID.
2023-09-05 15:03:05.868 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:05.870 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,WLANPassword) returns enum velux:klf200/WLANPassword.
2023-09-05 15:03:05.871 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:05.872 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,products) returns enum velux:klf200/products.
2023-09-05 15:03:05.874 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:05.875 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,scenes) returns enum velux:klf200/scenes.
2023-09-05 15:03:05.876 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:05.878 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,check) returns enum velux:klf200/check.
2023-09-05 15:03:05.879 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:05.880 [DEBUG] [.internal.handler.VeluxBridgeHandler] - refreshSchedulerJob() initiated by Thread[OH-thingHandler-8452,5,main] finished cycle 18018.
2023-09-05 15:03:05.882 [TRACE] [.internal.handler.VeluxBridgeHandler] - handleCommandCommsJob(Thread[OH-velux:klf200:192_168_170_89-1,5,main]): command REFRESH on channel velux:rollershutter:192_168_170_89:R_D07_2:position.
2023-09-05 15:03:05.883 [DEBUG] [.internal.handler.VeluxBridgeHandler] - handleCommandCommsJob(velux:rollershutter:192_168_170_89:R_D07_2:position,REFRESH) called.
2023-09-05 15:03:05.885 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:rollershutter,position) returns enum velux:rollershutter/position.
2023-09-05 15:03:05.886 [TRACE] [nternal.things.VeluxExistingProducts] - isDirty() returns false.
2023-09-05 15:03:05.887 [TRACE] [.internal.handler.VeluxBridgeHandler] - syncChannelsWithProducts(): no existing products with changed parameters.
2023-09-05 15:03:05.889 [TRACE] [.internal.handler.VeluxBridgeHandler] - handleCommandCommsJob(): work on refresh.
2023-09-05 15:03:05.890 [TRACE] [.internal.handler.VeluxBridgeHandler] - handleCommandCommsJob(): refreshing item velux:rollershutter:192_168_170_89:R_D07_2:position (type velux:rollershutter/position).
2023-09-05 15:03:05.892 [DEBUG] [rnal.handler.ChannelActuatorPosition] - handleRefresh(velux:rollershutter:192_168_170_89:R_D07_2:position,position,org.openhab.binding.velux.internal.handler.VeluxBridgeHandler@1fe580a) called.
2023-09-05 15:03:05.893 [TRACE] [nternal.things.VeluxExistingProducts] - getNoMembers() returns 1.
2023-09-05 15:03:05.896 [TRACE] [nal.handler.utils.ThingConfiguration] - exists(velux:rollershutter:192_168_170_89:R_D07_2:position,serial) returns true.
2023-09-05 15:03:05.898 [TRACE] [nal.handler.utils.ThingConfiguration] - getValue(velux:rollershutter:192_168_170_89:R_D07_2:position,serial) returns 00:00:00:00:00:00:00:00.
2023-09-05 15:03:05.899 [TRACE] [al.handler.utils.Thing2VeluxActuator] - mapThing2Velux(): in velux:rollershutter:192_168_170_89:R_D07_2:position serialNumber=null.
2023-09-05 15:03:05.901 [TRACE] [nal.handler.utils.ThingConfiguration] - exists(velux:rollershutter:192_168_170_89:R_D07_2:position,name) returns true.
2023-09-05 15:03:05.902 [TRACE] [nal.handler.utils.ThingConfiguration] - getValue(velux:rollershutter:192_168_170_89:R_D07_2:position,name) returns 1.
2023-09-05 15:03:05.904 [TRACE] [al.handler.utils.Thing2VeluxActuator] - mapThing2Velux(): in velux:rollershutter:192_168_170_89:R_D07_2:position name=1.
2023-09-05 15:03:05.906 [TRACE] [al.handler.utils.Thing2VeluxActuator] - mapThing2Velux(): in velux:rollershutter:192_168_170_89:R_D07_2:position uniqueIndex=1, proceeding.
2023-09-05 15:03:05.908 [TRACE] [nal.handler.utils.ThingConfiguration] - exists(velux:rollershutter:192_168_170_89:R_D07_2:position,inverted) returns true.
2023-09-05 15:03:05.909 [TRACE] [nal.handler.utils.ThingConfiguration] - getValue(velux:rollershutter:192_168_170_89:R_D07_2:position,inverted) returns false.
2023-09-05 15:03:05.911 [TRACE] [al.handler.utils.Thing2VeluxActuator] - mapThing2Velux(): in velux:rollershutter:192_168_170_89:R_D07_2:position isInverted=false.
2023-09-05 15:03:05.912 [TRACE] [nternal.things.VeluxExistingProducts] - isRegistered(String 1) returns false.
2023-09-05 15:03:05.913 [WARN ] [al.handler.utils.Thing2VeluxActuator] - mapThing2Velux(): actuator with uniqueIndex=1 is not registered
2023-09-05 15:03:05.918 [WARN ] [.internal.handler.VeluxBridgeHandler] - handleCommandCommsJob(velux:rollershutter:192_168_170_89:R_D07_2:position,REFRESH): updating of item velux:rollershutter:192_168_170_89:R_D07_2:position (type velux:rollershutter/position) failed.
2023-09-05 15:03:05.922 [TRACE] [.internal.handler.VeluxBridgeHandler] - handleCommandCommsJob(Thread[OH-velux:klf200:192_168_170_89-1,5,main]) done.
2023-09-05 15:03:05.923 [TRACE] [.internal.handler.VeluxBridgeHandler] - handleCommandCommsJob(Thread[OH-velux:klf200:192_168_170_89-1,5,main]): command REFRESH on channel velux:rollershutter:192_168_170_89:_0:limitMaximum.
2023-09-05 15:03:05.925 [DEBUG] [.internal.handler.VeluxBridgeHandler] - handleCommandCommsJob(velux:rollershutter:192_168_170_89:_0:limitMaximum,REFRESH) called.
2023-09-05 15:03:05.926 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:rollershutter,limitMaximum) returns enum velux:rollershutter/limitMaximum.
2023-09-05 15:03:05.928 [TRACE] [nternal.things.VeluxExistingProducts] - isDirty() returns false.
2023-09-05 15:03:05.929 [TRACE] [.internal.handler.VeluxBridgeHandler] - syncChannelsWithProducts(): no existing products with changed parameters.
2023-09-05 15:03:05.930 [TRACE] [.internal.handler.VeluxBridgeHandler] - handleCommandCommsJob(): work on refresh.
2023-09-05 15:03:05.932 [TRACE] [.internal.handler.VeluxBridgeHandler] - handleCommandCommsJob(): refreshing item velux:rollershutter:192_168_170_89:_0:limitMaximum (type velux:rollershutter/limitMaximum).
2023-09-05 15:03:05.933 [DEBUG] [al.handler.ChannelActuatorLimitation] - handleRefresh(velux:rollershutter:192_168_170_89:_0:limitMaximum,limitMaximum,org.openhab.binding.velux.internal.handler.VeluxBridgeHandler@1fe580a) called.
2023-09-05 15:03:05.934 [TRACE] [nternal.things.VeluxExistingProducts] - getNoMembers() returns 1.
2023-09-05 15:03:05.937 [TRACE] [nal.handler.utils.ThingConfiguration] - exists(velux:rollershutter:192_168_170_89:_0:limitMaximum,serial) returns true.
2023-09-05 15:03:05.939 [TRACE] [nal.handler.utils.ThingConfiguration] - getValue(velux:rollershutter:192_168_170_89:_0:limitMaximum,serial) returns 56:3B:07:26:17:10:32:93.
2023-09-05 15:03:05.942 [TRACE] [nal.handler.utils.ThingConfiguration] - exists(velux:rollershutter:192_168_170_89:_0:limitMaximum,inverted) returns true.
2023-09-05 15:03:05.943 [TRACE] [nal.handler.utils.ThingConfiguration] - getValue(velux:rollershutter:192_168_170_89:_0:limitMaximum,inverted) returns false.
2023-09-05 15:03:05.946 [TRACE] [nternal.things.VeluxExistingProducts] - isRegistered(String 56:3B:07:26:17:10:32:93) returns true.
2023-09-05 15:03:05.949 [TRACE] [nternal.things.VeluxExistingProducts] - get(56:3B:07:26:17:10:32:93) called.
2023-09-05 15:03:05.950 [TRACE] [al.handler.ChannelActuatorLimitation] - handleRefresh(): found actuator VeluxProduct(v2, creator:GW_GET_ALL_NODES_INFORMATION_REQ, dataSource:GATEWAY, name:#0, typeId:SLIDER_SHUTTER, bridgeIndex:0, state:5, serial:56:3B:07:26:17:10:32:93, position:A6A6, target:0000, functionalParameters:null).
2023-09-05 15:03:05.952 [TRACE] [rnal.bridge.VeluxBridgeGetLimitation] - getMaximumLimitation(nodeId=0) called.
2023-09-05 15:03:05.953 [TRACE] [internal.bridge.slip.SlipVeluxBridge] - bridgeAPI() called.
2023-09-05 15:03:05.960 [TRACE] [ng.velux.internal.bridge.VeluxBridge] - bridgeCommunicate(Retrieve Actuator Limitation) called.
2023-09-05 15:03:05.961 [TRACE] [ng.velux.internal.bridge.VeluxBridge] - isAuthenticated() returns true.
2023-09-05 15:03:05.963 [TRACE] [ng.velux.internal.bridge.VeluxBridge] - bridgeCommunicate(Retrieve Actuator Limitation,authenticated) called.
2023-09-05 15:03:05.964 [TRACE] [ng.velux.internal.bridge.VeluxBridge] - isAuthenticated() returns true.
2023-09-05 15:03:05.966 [TRACE] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate(BCP: Retrieve Actuator Limitation, authenticated) called.
2023-09-05 15:03:05.967 [TRACE] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate() 'Retrieve Actuator Limitation', authenticated
2023-09-05 15:03:05.979 [DEBUG] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate() [192.168.170.13] GW_GET_LIMITATION_STATUS_REQ => started => Thread[OH-velux:klf200:192_168_170_89-1,5,main] 
2023-09-05 15:03:05.980 [TRACE] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate() [192.168.170.13] GW_GET_LIMITATION_STATUS_REQ => send mode => preparing command 
2023-09-05 15:03:05.989 [TRACE] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate() [192.168.170.13] GW_GET_LIMITATION_STATUS_REQ => command ready => start sending 
2023-09-05 15:03:05.991 [TRACE] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate() [192.168.170.13] GW_GET_LIMITATION_STATUS_REQ => GW_GET_LIMITATION_STATUS_REQ => sending data => 1A A7 01 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 01
2023-09-05 15:03:06.179 [TRACE] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate() [192.168.170.13] GW_GET_LIMITATION_STATUS_REQ => GW_GET_LIMITATION_STATUS_CFM => received data => 1A A7 01
2023-09-05 15:03:06.180 [TRACE] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate() [192.168.170.13] GW_GET_LIMITATION_STATUS_REQ => GW_GET_LIMITATION_STATUS_CFM => applying data length => 3
2023-09-05 15:03:06.742 [TRACE] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate() [192.168.170.13] GW_GET_LIMITATION_STATUS_REQ => GW_LIMITATION_STATUS_NTF => received data => 1A A7 00 00 F7 FF C8 00 FF FF
2023-09-05 15:03:06.745 [TRACE] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate() [192.168.170.13] GW_GET_LIMITATION_STATUS_REQ => GW_LIMITATION_STATUS_NTF => applying data length => 10
2023-09-05 15:03:06.784 [DEBUG] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate() [192.168.170.13] GW_GET_LIMITATION_STATUS_REQ => finished => success
2023-09-05 15:03:06.786 [TRACE] [internal.things.VeluxProductPosition] - VeluxProductPosition(constructor with 51200 as veluxPosition) called.
2023-09-05 15:03:06.788 [TRACE] [internal.things.VeluxProductPosition] - VeluxProductPosition() created with percent-type 100.
2023-09-05 15:03:06.790 [DEBUG] [rnal.bridge.VeluxBridgeGetLimitation] - getMaximumLimitation() finished successfully.
2023-09-05 15:03:06.796 [DEBUG] [.internal.handler.VeluxBridgeHandler] - handleCommandCommsJob(): updating channel velux:rollershutter:192_168_170_89:_0:limitMaximum to 100.
2023-09-05 15:03:06.811 [TRACE] [.internal.handler.VeluxBridgeHandler] - handleCommandCommsJob(Thread[OH-velux:klf200:192_168_170_89-1,5,main]) done.
2023-09-05 15:03:06.816 [TRACE] [.internal.handler.VeluxBridgeHandler] - handleCommandCommsJob(Thread[OH-velux:klf200:192_168_170_89-1,5,main]): command REFRESH on channel velux:rollershutter:192_168_170_89:_0:position.
2023-09-05 15:03:06.818 [DEBUG] [.internal.handler.VeluxBridgeHandler] - handleCommandCommsJob(velux:rollershutter:192_168_170_89:_0:position,REFRESH) called.
2023-09-05 15:03:06.820 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:rollershutter,position) returns enum velux:rollershutter/position.
2023-09-05 15:03:06.821 [TRACE] [nternal.things.VeluxExistingProducts] - isDirty() returns false.
2023-09-05 15:03:06.823 [TRACE] [.internal.handler.VeluxBridgeHandler] - syncChannelsWithProducts(): no existing products with changed parameters.
2023-09-05 15:03:06.824 [TRACE] [.internal.handler.VeluxBridgeHandler] - handleCommandCommsJob(): work on refresh.
2023-09-05 15:03:06.826 [TRACE] [.internal.handler.VeluxBridgeHandler] - handleCommandCommsJob(): refreshing item velux:rollershutter:192_168_170_89:_0:position (type velux:rollershutter/position).
2023-09-05 15:03:06.828 [DEBUG] [rnal.handler.ChannelActuatorPosition] - handleRefresh(velux:rollershutter:192_168_170_89:_0:position,position,org.openhab.binding.velux.internal.handler.VeluxBridgeHandler@1fe580a) called.
2023-09-05 15:03:06.829 [TRACE] [nternal.things.VeluxExistingProducts] - getNoMembers() returns 1.
2023-09-05 15:03:06.832 [TRACE] [nternal.things.VeluxExistingProducts] - get(0) called.
2023-09-05 15:03:06.833 [TRACE] [internal.bridge.slip.SlipVeluxBridge] - bridgeAPI() called.
2023-09-05 15:03:06.836 [TRACE] [ng.velux.internal.bridge.VeluxBridge] - bridgeCommunicate(Retrieve Product Status) called.
2023-09-05 15:03:06.837 [TRACE] [ng.velux.internal.bridge.VeluxBridge] - isAuthenticated() returns true.
2023-09-05 15:03:06.839 [TRACE] [ng.velux.internal.bridge.VeluxBridge] - bridgeCommunicate(Retrieve Product Status,authenticated) called.
2023-09-05 15:03:06.840 [TRACE] [ng.velux.internal.bridge.VeluxBridge] - isAuthenticated() returns true.
2023-09-05 15:03:06.842 [TRACE] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate(BCP: Retrieve Product Status, authenticated) called.
2023-09-05 15:03:06.843 [TRACE] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate() 'Retrieve Product Status', authenticated
2023-09-05 15:03:06.847 [DEBUG] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate() [192.168.170.13] GW_STATUS_REQUEST_REQ => started => Thread[OH-velux:klf200:192_168_170_89-1,5,main] 
2023-09-05 15:03:06.849 [TRACE] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate() [192.168.170.13] GW_STATUS_REQUEST_REQ => send mode => preparing command 
2023-09-05 15:03:06.857 [TRACE] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate() [192.168.170.13] GW_STATUS_REQUEST_REQ => command ready => start sending 
2023-09-05 15:03:06.861 [TRACE] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate() [192.168.170.13] GW_STATUS_REQUEST_REQ => GW_STATUS_REQUEST_REQ => sending data => 0F C4 01 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 01 F0 00
2023-09-05 15:03:06.889 [TRACE] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate() [192.168.170.13] GW_STATUS_REQUEST_REQ => GW_COMMAND_RUN_STATUS_NTF => received data => 1A A7 01 00 00 A6 A6 00 01 2A 00 00 00
2023-09-05 15:03:06.890 [TRACE] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate() [192.168.170.13] GW_STATUS_REQUEST_REQ => GW_COMMAND_RUN_STATUS_NTF => parallelism allowed => continuing
2023-09-05 15:03:06.912 [TRACE] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate() [192.168.170.13] GW_STATUS_REQUEST_REQ => GW_SESSION_FINISHED_NTF => received data => 1A A7
2023-09-05 15:03:06.913 [TRACE] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate() [192.168.170.13] GW_STATUS_REQUEST_REQ => GW_SESSION_FINISHED_NTF => parallelism allowed => continuing
2023-09-05 15:03:06.939 [TRACE] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate() [192.168.170.13] GW_STATUS_REQUEST_REQ => GW_STATUS_REQUEST_CFM => received data => 0F C4 01
2023-09-05 15:03:06.940 [TRACE] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate() [192.168.170.13] GW_STATUS_REQUEST_REQ => GW_STATUS_REQUEST_CFM => applying data length => 3
2023-09-05 15:03:07.545 [TRACE] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate() [192.168.170.13] GW_STATUS_REQUEST_REQ => GW_STATUS_REQUEST_NTF => received data => 0F C4 01 00 00 01 01 01 00 A6 A6 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
2023-09-05 15:03:07.546 [TRACE] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate() [192.168.170.13] GW_STATUS_REQUEST_REQ => GW_STATUS_REQUEST_NTF => applying data length => 59
2023-09-05 15:03:07.580 [TRACE] [g.velux.internal.things.VeluxProduct] - VeluxProduct(v2, name=unknown) [notification product] created.
2023-09-05 15:03:07.585 [DEBUG] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate() [192.168.170.13] GW_STATUS_REQUEST_REQ => finished => success
2023-09-05 15:03:07.587 [TRACE] [ernal.bridge.slip.SCgetProductStatus] - getProduct(): returning VeluxProduct(v2, creator:GW_STATUS_REQUEST_REQ, dataSource:GATEWAY, name:unknown, typeId:UNDEFTYPE, bridgeIndex:0, state:5, serial:00:00:00:00:00:00:00:00, position:A6A6, target:D400, functionalParameters:null).
2023-09-05 15:03:07.588 [TRACE] [nternal.things.VeluxExistingProducts] - get(0) called.
2023-09-05 15:03:07.590 [TRACE] [nternal.things.VeluxExistingProducts] - isRegistered(ProductBridgeIndex 0) returns true.
2023-09-05 15:03:07.591 [TRACE] [nternal.things.VeluxExistingProducts] - get(0) called.
2023-09-05 15:03:07.593 [DEBUG] [nternal.things.VeluxExistingProducts] - update() newProduct:VeluxProduct(v2, creator:GW_STATUS_REQUEST_REQ, dataSource:GATEWAY, name:unknown, typeId:UNDEFTYPE, bridgeIndex:0, state:5, serial:00:00:00:00:00:00:00:00, position:A6A6, target:D400, functionalParameters:null) (identical)
2023-09-05 15:03:07.595 [DEBUG] [nternal.things.VeluxExistingProducts] - update() theProduct:VeluxProduct(v2, creator:GW_GET_ALL_NODES_INFORMATION_REQ, dataSource:GATEWAY, name:#0, typeId:SLIDER_SHUTTER, bridgeIndex:0, state:5, serial:56:3B:07:26:17:10:32:93, position:A6A6, target:0000, functionalParameters:null) (unchanged)
2023-09-05 15:03:07.597 [TRACE] [nternal.things.VeluxExistingProducts] - get(0) called.
2023-09-05 15:03:07.598 [TRACE] [internal.things.VeluxProductPosition] - VeluxProductPosition(constructor with 42662 as veluxPosition) called.
2023-09-05 15:03:07.600 [TRACE] [internal.things.VeluxProductPosition] - VeluxProductPosition() created with percent-type 83.
2023-09-05 15:03:07.603 [DEBUG] [.internal.handler.VeluxBridgeHandler] - handleCommandCommsJob(): updating channel velux:rollershutter:192_168_170_89:_0:position to 83.
2023-09-05 15:03:07.609 [TRACE] [.internal.handler.VeluxBridgeHandler] - handleCommandCommsJob(Thread[OH-velux:klf200:192_168_170_89-1,5,main]) done.
2023-09-05 15:03:14.473 [DEBUG] [.internal.handler.VeluxBridgeHandler] - refreshSchedulerJob() initiated by Thread[OH-thingHandler-8453,5,main] starting cycle 18019.
2023-09-05 15:03:14.474 [TRACE] [.internal.handler.VeluxBridgeHandler] - refreshSchedulerJob(): processing of possible HSM messages.
2023-09-05 15:03:14.475 [TRACE] [.internal.handler.VeluxBridgeHandler] - refreshSchedulerJob(): loop through all (child things and bridge) linked channels needing a refresh
2023-09-05 15:03:14.476 [TRACE] [.internal.handler.VeluxBridgeHandler] - getHouseStatusCommsJob() initiated by Thread[OH-velux:klf200:192_168_170_89-1,5,main] will process HouseStatus.
2023-09-05 15:03:14.477 [TRACE] [elux.internal.handler.BridgeChannels] - getAllLinkedChannelUIDs() returns [velux:rollershutter:192_168_170_89:R_D07_2:position, velux:rollershutter:192_168_170_89:_0:limitMaximum, velux:rollershutter:192_168_170_89:_0:position].
2023-09-05 15:03:14.477 [TRACE] [nal.bridge.VeluxBridgeGetHouseStatus] - evaluateState() called.
2023-09-05 15:03:14.478 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:rollershutter,position) returns enum velux:rollershutter/position.
2023-09-05 15:03:14.478 [TRACE] [internal.bridge.slip.SlipVeluxBridge] - bridgeAPI() called.
2023-09-05 15:03:14.480 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:14.480 [TRACE] [ng.velux.internal.bridge.VeluxBridge] - bridgeCommunicate(Retrieve House Status) called.
2023-09-05 15:03:14.481 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:rollershutter,limitMaximum) returns enum velux:rollershutter/limitMaximum.
2023-09-05 15:03:14.482 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:14.484 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:rollershutter,position) returns enum velux:rollershutter/position.
2023-09-05 15:03:14.482 [TRACE] [ng.velux.internal.bridge.VeluxBridge] - isAuthenticated() returns true.
2023-09-05 15:03:14.485 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:14.485 [TRACE] [ng.velux.internal.bridge.VeluxBridge] - bridgeCommunicate(Retrieve House Status,authenticated) called.
2023-09-05 15:03:14.486 [TRACE] [ng.velux.internal.bridge.VeluxBridge] - isAuthenticated() returns true.
2023-09-05 15:03:14.486 [TRACE] [.internal.handler.VeluxBridgeHandler] - refreshSchedulerJob(): loop through properties needing a refresh
2023-09-05 15:03:14.488 [TRACE] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate(BCP: Retrieve House Status, authenticated) called.
2023-09-05 15:03:14.488 [TRACE] [binding.velux.internal.VeluxItemType] - getPropertyEntriesByThing(velux:klf200) returns [velux:klf200/firmware, velux:klf200/address, velux:klf200/subnetMask, velux:klf200/defaultGW, velux:klf200/DHCP, velux:klf200/WLANSSID, velux:klf200/WLANPassword, velux:klf200/products, velux:klf200/scenes, velux:klf200/check].
2023-09-05 15:03:14.489 [TRACE] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate() 'Retrieve House Status', authenticated
2023-09-05 15:03:14.489 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,firmware) returns enum velux:klf200/firmware.
2023-09-05 15:03:14.490 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:14.491 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,address) returns enum velux:klf200/address.
2023-09-05 15:03:14.491 [DEBUG] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate() [192.168.170.13] GW_OPENHAB_RECEIVEONLY => started => Thread[OH-velux:klf200:192_168_170_89-1,5,main] 
2023-09-05 15:03:14.493 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:14.494 [TRACE] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate() [192.168.170.13] GW_OPENHAB_RECEIVEONLY => receive-only mode => checking messages 
2023-09-05 15:03:14.494 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,subnetMask) returns enum velux:klf200/subnetMask.
2023-09-05 15:03:14.495 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:14.496 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,defaultGW) returns enum velux:klf200/defaultGW.
2023-09-05 15:03:14.498 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:14.500 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,DHCP) returns enum velux:klf200/DHCP.
2023-09-05 15:03:14.501 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:14.503 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,WLANSSID) returns enum velux:klf200/WLANSSID.
2023-09-05 15:03:14.505 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:14.506 [TRACE] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate() [192.168.170.13] GW_OPENHAB_RECEIVEONLY => message(s) waiting => start reading 
2023-09-05 15:03:14.506 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,WLANPassword) returns enum velux:klf200/WLANPassword.
2023-09-05 15:03:14.508 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:14.510 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,products) returns enum velux:klf200/products.
2023-09-05 15:03:14.511 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:14.513 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,scenes) returns enum velux:klf200/scenes.
2023-09-05 15:03:14.515 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:14.516 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,check) returns enum velux:klf200/check.
2023-09-05 15:03:14.518 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:14.520 [DEBUG] [.internal.handler.VeluxBridgeHandler] - refreshSchedulerJob() initiated by Thread[OH-thingHandler-8453,5,main] finished cycle 18019.
2023-09-05 15:03:14.539 [TRACE] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate() [192.168.170.13] GW_OPENHAB_RECEIVEONLY => GW_SESSION_FINISHED_NTF => received data => 0F C4
2023-09-05 15:03:14.541 [TRACE] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate() [192.168.170.13] GW_OPENHAB_RECEIVEONLY => GW_SESSION_FINISHED_NTF => parallelism allowed => continuing
2023-09-05 15:03:15.882 [DEBUG] [.internal.handler.VeluxBridgeHandler] - refreshSchedulerJob() initiated by Thread[OH-thingHandler-8449,5,main] starting cycle 18020.
2023-09-05 15:03:15.883 [TRACE] [.internal.handler.VeluxBridgeHandler] - refreshSchedulerJob(): processing of possible HSM messages.
2023-09-05 15:03:15.884 [TRACE] [.internal.handler.VeluxBridgeHandler] - refreshSchedulerJob(): loop through all (child things and bridge) linked channels needing a refresh
2023-09-05 15:03:15.884 [TRACE] [elux.internal.handler.BridgeChannels] - getAllLinkedChannelUIDs() returns [velux:rollershutter:192_168_170_89:R_D07_2:position, velux:rollershutter:192_168_170_89:_0:limitMaximum, velux:rollershutter:192_168_170_89:_0:position].
2023-09-05 15:03:15.885 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:rollershutter,position) returns enum velux:rollershutter/position.
2023-09-05 15:03:15.886 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:15.887 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:rollershutter,limitMaximum) returns enum velux:rollershutter/limitMaximum.
2023-09-05 15:03:15.887 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:15.888 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:rollershutter,position) returns enum velux:rollershutter/position.
2023-09-05 15:03:15.889 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:15.890 [TRACE] [.internal.handler.VeluxBridgeHandler] - refreshSchedulerJob(): loop through properties needing a refresh
2023-09-05 15:03:15.890 [TRACE] [binding.velux.internal.VeluxItemType] - getPropertyEntriesByThing(velux:klf200) returns [velux:klf200/firmware, velux:klf200/address, velux:klf200/subnetMask, velux:klf200/defaultGW, velux:klf200/DHCP, velux:klf200/WLANSSID, velux:klf200/WLANPassword, velux:klf200/products, velux:klf200/scenes, velux:klf200/check].
2023-09-05 15:03:15.892 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,firmware) returns enum velux:klf200/firmware.
2023-09-05 15:03:15.893 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:15.895 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,address) returns enum velux:klf200/address.
2023-09-05 15:03:15.896 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:15.898 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,subnetMask) returns enum velux:klf200/subnetMask.
2023-09-05 15:03:15.899 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:15.900 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,defaultGW) returns enum velux:klf200/defaultGW.
2023-09-05 15:03:15.902 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:15.903 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,DHCP) returns enum velux:klf200/DHCP.
2023-09-05 15:03:15.905 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:15.906 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,WLANSSID) returns enum velux:klf200/WLANSSID.
2023-09-05 15:03:15.908 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:15.909 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,WLANPassword) returns enum velux:klf200/WLANPassword.
2023-09-05 15:03:15.911 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:15.912 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,products) returns enum velux:klf200/products.
2023-09-05 15:03:15.914 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:15.915 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,scenes) returns enum velux:klf200/scenes.
2023-09-05 15:03:15.917 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:15.918 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,check) returns enum velux:klf200/check.
2023-09-05 15:03:15.919 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:15.921 [DEBUG] [.internal.handler.VeluxBridgeHandler] - refreshSchedulerJob() initiated by Thread[OH-thingHandler-8449,5,main] finished cycle 18020.
2023-09-05 15:03:17.669 [DEBUG] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate() [192.168.170.13] GW_OPENHAB_RECEIVEONLY => finished => success
2023-09-05 15:03:17.670 [DEBUG] [nal.bridge.VeluxBridgeGetHouseStatus] - evaluateState() finished with failure.
2023-09-05 15:03:17.671 [TRACE] [.internal.handler.VeluxBridgeHandler] - getHouseStatusCommsJob(): => GetHouseStatus() => no updates
2023-09-05 15:03:17.672 [TRACE] [.internal.handler.VeluxBridgeHandler] - getHouseStatusCommsJob() initiated by Thread[OH-velux:klf200:192_168_170_89-1,5,main] has finished.
2023-09-05 15:03:17.673 [TRACE] [.internal.handler.VeluxBridgeHandler] - getHouseStatusCommsJob() initiated by Thread[OH-velux:klf200:192_168_170_89-1,5,main] will process HouseStatus.
2023-09-05 15:03:17.674 [TRACE] [nal.bridge.VeluxBridgeGetHouseStatus] - evaluateState() called.
2023-09-05 15:03:17.676 [TRACE] [internal.bridge.slip.SlipVeluxBridge] - bridgeAPI() called.
2023-09-05 15:03:17.677 [TRACE] [ng.velux.internal.bridge.VeluxBridge] - bridgeCommunicate(Retrieve House Status) called.
2023-09-05 15:03:17.678 [TRACE] [ng.velux.internal.bridge.VeluxBridge] - isAuthenticated() returns true.
2023-09-05 15:03:17.680 [TRACE] [ng.velux.internal.bridge.VeluxBridge] - bridgeCommunicate(Retrieve House Status,authenticated) called.
2023-09-05 15:03:17.681 [TRACE] [ng.velux.internal.bridge.VeluxBridge] - isAuthenticated() returns true.
2023-09-05 15:03:17.682 [TRACE] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate(BCP: Retrieve House Status, authenticated) called.
2023-09-05 15:03:17.684 [TRACE] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate() 'Retrieve House Status', authenticated
2023-09-05 15:03:17.687 [DEBUG] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate() [192.168.170.13] GW_OPENHAB_RECEIVEONLY => started => Thread[OH-velux:klf200:192_168_170_89-1,5,main] 
2023-09-05 15:03:17.688 [TRACE] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate() [192.168.170.13] GW_OPENHAB_RECEIVEONLY => receive-only mode => checking messages 
2023-09-05 15:03:17.697 [TRACE] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate() [192.168.170.13] GW_OPENHAB_RECEIVEONLY => no waiting messages => done 
2023-09-05 15:03:17.699 [DEBUG] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate() [192.168.170.13] GW_OPENHAB_RECEIVEONLY => finished => success
2023-09-05 15:03:17.700 [DEBUG] [nal.bridge.VeluxBridgeGetHouseStatus] - evaluateState() finished with failure.
2023-09-05 15:03:17.701 [TRACE] [.internal.handler.VeluxBridgeHandler] - getHouseStatusCommsJob(): => GetHouseStatus() => no updates
2023-09-05 15:03:17.703 [TRACE] [.internal.handler.VeluxBridgeHandler] - getHouseStatusCommsJob() initiated by Thread[OH-velux:klf200:192_168_170_89-1,5,main] has finished.
2023-09-05 15:03:24.522 [DEBUG] [.internal.handler.VeluxBridgeHandler] - refreshSchedulerJob() initiated by Thread[OH-thingHandler-8452,5,main] starting cycle 18021.
2023-09-05 15:03:24.523 [TRACE] [.internal.handler.VeluxBridgeHandler] - refreshSchedulerJob(): processing of possible HSM messages.
2023-09-05 15:03:24.524 [TRACE] [.internal.handler.VeluxBridgeHandler] - refreshSchedulerJob(): loop through all (child things and bridge) linked channels needing a refresh
2023-09-05 15:03:24.524 [TRACE] [.internal.handler.VeluxBridgeHandler] - getHouseStatusCommsJob() initiated by Thread[OH-velux:klf200:192_168_170_89-1,5,main] will process HouseStatus.
2023-09-05 15:03:24.525 [TRACE] [nal.bridge.VeluxBridgeGetHouseStatus] - evaluateState() called.
2023-09-05 15:03:24.525 [TRACE] [elux.internal.handler.BridgeChannels] - getAllLinkedChannelUIDs() returns [velux:rollershutter:192_168_170_89:R_D07_2:position, velux:rollershutter:192_168_170_89:_0:limitMaximum, velux:rollershutter:192_168_170_89:_0:position].
2023-09-05 15:03:24.526 [TRACE] [internal.bridge.slip.SlipVeluxBridge] - bridgeAPI() called.
2023-09-05 15:03:24.528 [TRACE] [ng.velux.internal.bridge.VeluxBridge] - bridgeCommunicate(Retrieve House Status) called.
2023-09-05 15:03:24.529 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:rollershutter,position) returns enum velux:rollershutter/position.
2023-09-05 15:03:24.530 [TRACE] [ng.velux.internal.bridge.VeluxBridge] - isAuthenticated() returns true.
2023-09-05 15:03:24.533 [TRACE] [ng.velux.internal.bridge.VeluxBridge] - bridgeCommunicate(Retrieve House Status,authenticated) called.
2023-09-05 15:03:24.535 [TRACE] [ng.velux.internal.bridge.VeluxBridge] - isAuthenticated() returns true.
2023-09-05 15:03:24.536 [TRACE] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate(BCP: Retrieve House Status, authenticated) called.
2023-09-05 15:03:24.540 [TRACE] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate() 'Retrieve House Status', authenticated
2023-09-05 15:03:24.541 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:24.543 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:rollershutter,limitMaximum) returns enum velux:rollershutter/limitMaximum.
2023-09-05 15:03:24.544 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:24.546 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:rollershutter,position) returns enum velux:rollershutter/position.
2023-09-05 15:03:24.544 [DEBUG] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate() [192.168.170.13] GW_OPENHAB_RECEIVEONLY => started => Thread[OH-velux:klf200:192_168_170_89-1,5,main] 
2023-09-05 15:03:24.548 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:24.549 [TRACE] [.internal.handler.VeluxBridgeHandler] - refreshSchedulerJob(): loop through properties needing a refresh
2023-09-05 15:03:24.551 [TRACE] [binding.velux.internal.VeluxItemType] - getPropertyEntriesByThing(velux:klf200) returns [velux:klf200/firmware, velux:klf200/address, velux:klf200/subnetMask, velux:klf200/defaultGW, velux:klf200/DHCP, velux:klf200/WLANSSID, velux:klf200/WLANPassword, velux:klf200/products, velux:klf200/scenes, velux:klf200/check].
2023-09-05 15:03:24.552 [TRACE] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate() [192.168.170.13] GW_OPENHAB_RECEIVEONLY => receive-only mode => checking messages 
2023-09-05 15:03:24.553 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,firmware) returns enum velux:klf200/firmware.
2023-09-05 15:03:24.554 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:24.556 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,address) returns enum velux:klf200/address.
2023-09-05 15:03:24.557 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:24.559 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,subnetMask) returns enum velux:klf200/subnetMask.
2023-09-05 15:03:24.560 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:24.561 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,defaultGW) returns enum velux:klf200/defaultGW.
2023-09-05 15:03:24.563 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:24.564 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,DHCP) returns enum velux:klf200/DHCP.
2023-09-05 15:03:24.566 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:24.567 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,WLANSSID) returns enum velux:klf200/WLANSSID.
2023-09-05 15:03:24.569 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:24.568 [TRACE] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate() [192.168.170.13] GW_OPENHAB_RECEIVEONLY => no waiting messages => done 
2023-09-05 15:03:24.570 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,WLANPassword) returns enum velux:klf200/WLANPassword.
2023-09-05 15:03:24.572 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:24.572 [DEBUG] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate() [192.168.170.13] GW_OPENHAB_RECEIVEONLY => finished => success
2023-09-05 15:03:24.573 [DEBUG] [nal.bridge.VeluxBridgeGetHouseStatus] - evaluateState() finished with failure.
2023-09-05 15:03:24.574 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,products) returns enum velux:klf200/products.
2023-09-05 15:03:24.575 [TRACE] [.internal.handler.VeluxBridgeHandler] - getHouseStatusCommsJob(): => GetHouseStatus() => no updates
2023-09-05 15:03:24.576 [TRACE] [.internal.handler.VeluxBridgeHandler] - getHouseStatusCommsJob() initiated by Thread[OH-velux:klf200:192_168_170_89-1,5,main] has finished.
2023-09-05 15:03:24.575 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:24.577 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,scenes) returns enum velux:klf200/scenes.
2023-09-05 15:03:24.579 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:24.580 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,check) returns enum velux:klf200/check.
2023-09-05 15:03:24.581 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:24.583 [DEBUG] [.internal.handler.VeluxBridgeHandler] - refreshSchedulerJob() initiated by Thread[OH-thingHandler-8452,5,main] finished cycle 18021.
2023-09-05 15:03:24.927 [TRACE] [rnal.discovery.VeluxDiscoveryService] - startScan() called.
2023-09-05 15:03:24.928 [DEBUG] [rnal.discovery.VeluxDiscoveryService] - startScan(): creating a thing of type binding.
2023-09-05 15:03:24.929 [TRACE] [ng.velux.internal.utils.Localization] - getText() returns Velux Binding Informationselement.
2023-09-05 15:03:24.931 [DEBUG] [rnal.discovery.VeluxDiscoveryService] - startScan(): registering new thing DiscoveryResult [thingUID=velux:binding:org_openhab_binding_velux, properties={bundleVersion=org.openhab.binding.velux_4.0.2 [318]}, representationProperty=bundleVersion, flag=NEW, label=Velux Binding Informationselement, bridgeUID=null, ttl=-1, timestamp=1693919004931].
2023-09-05 15:03:24.934 [DEBUG] [rnal.discovery.VeluxDiscoveryService] - startScan(): VeluxDiscoveryService cannot proceed due to missing Velux bridge(s).
2023-09-05 15:03:24.935 [TRACE] [rnal.discovery.VeluxDiscoveryService] - startScan() done.
2023-09-05 15:03:25.922 [DEBUG] [.internal.handler.VeluxBridgeHandler] - refreshSchedulerJob() initiated by Thread[OH-thingHandler-8449,5,main] starting cycle 18022.
2023-09-05 15:03:25.924 [TRACE] [.internal.handler.VeluxBridgeHandler] - refreshSchedulerJob(): processing of possible HSM messages.
2023-09-05 15:03:25.926 [TRACE] [.internal.handler.VeluxBridgeHandler] - refreshSchedulerJob(): loop through all (child things and bridge) linked channels needing a refresh
2023-09-05 15:03:25.926 [TRACE] [.internal.handler.VeluxBridgeHandler] - getHouseStatusCommsJob() initiated by Thread[OH-velux:klf200:192_168_170_89-1,5,main] will process HouseStatus.
2023-09-05 15:03:25.928 [TRACE] [nal.bridge.VeluxBridgeGetHouseStatus] - evaluateState() called.
2023-09-05 15:03:25.928 [TRACE] [elux.internal.handler.BridgeChannels] - getAllLinkedChannelUIDs() returns [velux:rollershutter:192_168_170_89:R_D07_2:position, velux:rollershutter:192_168_170_89:_0:limitMaximum, velux:rollershutter:192_168_170_89:_0:position].
2023-09-05 15:03:25.929 [TRACE] [internal.bridge.slip.SlipVeluxBridge] - bridgeAPI() called.
2023-09-05 15:03:25.929 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:rollershutter,position) returns enum velux:rollershutter/position.
2023-09-05 15:03:25.930 [TRACE] [ng.velux.internal.bridge.VeluxBridge] - bridgeCommunicate(Retrieve House Status) called.
2023-09-05 15:03:25.930 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:25.930 [TRACE] [ng.velux.internal.bridge.VeluxBridge] - isAuthenticated() returns true.
2023-09-05 15:03:25.931 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:rollershutter,limitMaximum) returns enum velux:rollershutter/limitMaximum.
2023-09-05 15:03:25.932 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:25.933 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:rollershutter,position) returns enum velux:rollershutter/position.
2023-09-05 15:03:25.935 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:25.936 [TRACE] [.internal.handler.VeluxBridgeHandler] - refreshSchedulerJob(): loop through properties needing a refresh
2023-09-05 15:03:25.932 [TRACE] [ng.velux.internal.bridge.VeluxBridge] - bridgeCommunicate(Retrieve House Status,authenticated) called.
2023-09-05 15:03:25.937 [TRACE] [binding.velux.internal.VeluxItemType] - getPropertyEntriesByThing(velux:klf200) returns [velux:klf200/firmware, velux:klf200/address, velux:klf200/subnetMask, velux:klf200/defaultGW, velux:klf200/DHCP, velux:klf200/WLANSSID, velux:klf200/WLANPassword, velux:klf200/products, velux:klf200/scenes, velux:klf200/check].
2023-09-05 15:03:25.938 [TRACE] [ng.velux.internal.bridge.VeluxBridge] - isAuthenticated() returns true.
2023-09-05 15:03:25.938 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,firmware) returns enum velux:klf200/firmware.
2023-09-05 15:03:25.939 [TRACE] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate(BCP: Retrieve House Status, authenticated) called.
2023-09-05 15:03:25.939 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:25.940 [TRACE] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate() 'Retrieve House Status', authenticated
2023-09-05 15:03:25.940 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,address) returns enum velux:klf200/address.
2023-09-05 15:03:25.941 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:25.942 [DEBUG] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate() [192.168.170.13] GW_OPENHAB_RECEIVEONLY => started => Thread[OH-velux:klf200:192_168_170_89-1,5,main] 
2023-09-05 15:03:25.943 [TRACE] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate() [192.168.170.13] GW_OPENHAB_RECEIVEONLY => receive-only mode => checking messages 
2023-09-05 15:03:25.943 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,subnetMask) returns enum velux:klf200/subnetMask.
2023-09-05 15:03:25.944 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:25.945 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,defaultGW) returns enum velux:klf200/defaultGW.
2023-09-05 15:03:25.946 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:25.947 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,DHCP) returns enum velux:klf200/DHCP.
2023-09-05 15:03:25.948 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:25.949 [TRACE] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate() [192.168.170.13] GW_OPENHAB_RECEIVEONLY => no waiting messages => done 
2023-09-05 15:03:25.949 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,WLANSSID) returns enum velux:klf200/WLANSSID.
2023-09-05 15:03:25.950 [DEBUG] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate() [192.168.170.13] GW_OPENHAB_RECEIVEONLY => finished => success
2023-09-05 15:03:25.950 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:25.951 [DEBUG] [nal.bridge.VeluxBridgeGetHouseStatus] - evaluateState() finished with failure.
2023-09-05 15:03:25.951 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,WLANPassword) returns enum velux:klf200/WLANPassword.
2023-09-05 15:03:25.952 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:25.953 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,products) returns enum velux:klf200/products.
2023-09-05 15:03:25.954 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:25.956 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,scenes) returns enum velux:klf200/scenes.
2023-09-05 15:03:25.957 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:25.959 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,check) returns enum velux:klf200/check.
2023-09-05 15:03:25.960 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:25.962 [DEBUG] [.internal.handler.VeluxBridgeHandler] - refreshSchedulerJob() initiated by Thread[OH-thingHandler-8449,5,main] finished cycle 18022.
2023-09-05 15:03:25.963 [TRACE] [.internal.handler.VeluxBridgeHandler] - getHouseStatusCommsJob(): => GetHouseStatus() => no updates
2023-09-05 15:03:25.965 [TRACE] [.internal.handler.VeluxBridgeHandler] - getHouseStatusCommsJob() initiated by Thread[OH-velux:klf200:192_168_170_89-1,5,main] has finished.
2023-09-05 15:03:34.584 [DEBUG] [.internal.handler.VeluxBridgeHandler] - refreshSchedulerJob() initiated by Thread[OH-thingHandler-8453,5,main] starting cycle 18023.
2023-09-05 15:03:34.653 [TRACE] [.internal.handler.VeluxBridgeHandler] - refreshSchedulerJob(): processing of possible HSM messages.
2023-09-05 15:03:34.655 [TRACE] [.internal.handler.VeluxBridgeHandler] - refreshSchedulerJob(): loop through all (child things and bridge) linked channels needing a refresh
2023-09-05 15:03:34.656 [TRACE] [.internal.handler.VeluxBridgeHandler] - getHouseStatusCommsJob() initiated by Thread[OH-velux:klf200:192_168_170_89-1,5,main] will process HouseStatus.
2023-09-05 15:03:34.659 [TRACE] [elux.internal.handler.BridgeChannels] - getAllLinkedChannelUIDs() returns [velux:rollershutter:192_168_170_89:R_D07_2:position, velux:rollershutter:192_168_170_89:_0:limitMaximum, velux:rollershutter:192_168_170_89:_0:position].
2023-09-05 15:03:34.658 [TRACE] [nal.bridge.VeluxBridgeGetHouseStatus] - evaluateState() called.
2023-09-05 15:03:34.661 [TRACE] [internal.bridge.slip.SlipVeluxBridge] - bridgeAPI() called.
2023-09-05 15:03:34.661 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:rollershutter,position) returns enum velux:rollershutter/position.
2023-09-05 15:03:34.662 [TRACE] [ng.velux.internal.bridge.VeluxBridge] - bridgeCommunicate(Retrieve House Status) called.
2023-09-05 15:03:34.665 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:34.665 [TRACE] [ng.velux.internal.bridge.VeluxBridge] - isAuthenticated() returns true.
2023-09-05 15:03:34.667 [TRACE] [ng.velux.internal.bridge.VeluxBridge] - bridgeCommunicate(Retrieve House Status,authenticated) called.
2023-09-05 15:03:34.669 [TRACE] [ng.velux.internal.bridge.VeluxBridge] - isAuthenticated() returns true.
2023-09-05 15:03:34.669 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:rollershutter,limitMaximum) returns enum velux:rollershutter/limitMaximum.
2023-09-05 15:03:34.670 [TRACE] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate(BCP: Retrieve House Status, authenticated) called.
2023-09-05 15:03:34.673 [TRACE] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate() 'Retrieve House Status', authenticated
2023-09-05 15:03:34.675 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:34.677 [DEBUG] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate() [192.168.170.13] GW_OPENHAB_RECEIVEONLY => started => Thread[OH-velux:klf200:192_168_170_89-1,5,main] 
2023-09-05 15:03:34.679 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:rollershutter,position) returns enum velux:rollershutter/position.
2023-09-05 15:03:34.679 [TRACE] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate() [192.168.170.13] GW_OPENHAB_RECEIVEONLY => receive-only mode => checking messages 
2023-09-05 15:03:34.681 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:34.682 [TRACE] [.internal.handler.VeluxBridgeHandler] - refreshSchedulerJob(): loop through properties needing a refresh
2023-09-05 15:03:34.683 [TRACE] [binding.velux.internal.VeluxItemType] - getPropertyEntriesByThing(velux:klf200) returns [velux:klf200/firmware, velux:klf200/address, velux:klf200/subnetMask, velux:klf200/defaultGW, velux:klf200/DHCP, velux:klf200/WLANSSID, velux:klf200/WLANPassword, velux:klf200/products, velux:klf200/scenes, velux:klf200/check].
2023-09-05 15:03:34.689 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,firmware) returns enum velux:klf200/firmware.
2023-09-05 15:03:34.689 [TRACE] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate() [192.168.170.13] GW_OPENHAB_RECEIVEONLY => no waiting messages => done 
2023-09-05 15:03:34.690 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:34.690 [DEBUG] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate() [192.168.170.13] GW_OPENHAB_RECEIVEONLY => finished => success
2023-09-05 15:03:34.691 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,address) returns enum velux:klf200/address.
2023-09-05 15:03:34.691 [DEBUG] [nal.bridge.VeluxBridgeGetHouseStatus] - evaluateState() finished with failure.
2023-09-05 15:03:34.692 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:34.692 [TRACE] [.internal.handler.VeluxBridgeHandler] - getHouseStatusCommsJob(): => GetHouseStatus() => no updates
2023-09-05 15:03:34.693 [TRACE] [.internal.handler.VeluxBridgeHandler] - getHouseStatusCommsJob() initiated by Thread[OH-velux:klf200:192_168_170_89-1,5,main] has finished.
2023-09-05 15:03:34.693 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,subnetMask) returns enum velux:klf200/subnetMask.
2023-09-05 15:03:34.697 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:34.699 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,defaultGW) returns enum velux:klf200/defaultGW.
2023-09-05 15:03:34.701 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:34.703 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,DHCP) returns enum velux:klf200/DHCP.
2023-09-05 15:03:34.705 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:34.707 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,WLANSSID) returns enum velux:klf200/WLANSSID.
2023-09-05 15:03:34.709 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:34.710 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,WLANPassword) returns enum velux:klf200/WLANPassword.
2023-09-05 15:03:34.712 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:34.714 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,products) returns enum velux:klf200/products.
2023-09-05 15:03:34.715 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:34.716 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,scenes) returns enum velux:klf200/scenes.
2023-09-05 15:03:34.718 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:34.719 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,check) returns enum velux:klf200/check.
2023-09-05 15:03:34.721 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:34.722 [DEBUG] [.internal.handler.VeluxBridgeHandler] - refreshSchedulerJob() initiated by Thread[OH-thingHandler-8453,5,main] finished cycle 18023.
2023-09-05 15:03:35.963 [DEBUG] [.internal.handler.VeluxBridgeHandler] - refreshSchedulerJob() initiated by Thread[OH-thingHandler-8452,5,main] starting cycle 18024.
2023-09-05 15:03:35.964 [TRACE] [.internal.handler.VeluxBridgeHandler] - refreshSchedulerJob(): processing of possible HSM messages.
2023-09-05 15:03:35.965 [TRACE] [.internal.handler.VeluxBridgeHandler] - refreshSchedulerJob(): loop through all (child things and bridge) linked channels needing a refresh
2023-09-05 15:03:35.965 [TRACE] [.internal.handler.VeluxBridgeHandler] - getHouseStatusCommsJob() initiated by Thread[OH-velux:klf200:192_168_170_89-1,5,main] will process HouseStatus.
2023-09-05 15:03:35.966 [TRACE] [elux.internal.handler.BridgeChannels] - getAllLinkedChannelUIDs() returns [velux:rollershutter:192_168_170_89:R_D07_2:position, velux:rollershutter:192_168_170_89:_0:limitMaximum, velux:rollershutter:192_168_170_89:_0:position].
2023-09-05 15:03:35.966 [TRACE] [nal.bridge.VeluxBridgeGetHouseStatus] - evaluateState() called.
2023-09-05 15:03:35.967 [TRACE] [internal.bridge.slip.SlipVeluxBridge] - bridgeAPI() called.
2023-09-05 15:03:35.968 [TRACE] [ng.velux.internal.bridge.VeluxBridge] - bridgeCommunicate(Retrieve House Status) called.
2023-09-05 15:03:35.969 [TRACE] [ng.velux.internal.bridge.VeluxBridge] - isAuthenticated() returns true.
2023-09-05 15:03:35.970 [TRACE] [ng.velux.internal.bridge.VeluxBridge] - bridgeCommunicate(Retrieve House Status,authenticated) called.
2023-09-05 15:03:35.967 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:rollershutter,position) returns enum velux:rollershutter/position.
2023-09-05 15:03:35.972 [TRACE] [ng.velux.internal.bridge.VeluxBridge] - isAuthenticated() returns true.
2023-09-05 15:03:35.973 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning true, as item is to be refreshed, now.
2023-09-05 15:03:35.973 [TRACE] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate(BCP: Retrieve House Status, authenticated) called.
2023-09-05 15:03:35.974 [TRACE] [.internal.handler.VeluxBridgeHandler] - refreshSchedulerJob(): refreshing channel velux:rollershutter:192_168_170_89:R_D07_2:position.
2023-09-05 15:03:35.974 [TRACE] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate() 'Retrieve House Status', authenticated
2023-09-05 15:03:35.976 [TRACE] [.internal.handler.VeluxBridgeHandler] - handleCommand(Thread[OH-thingHandler-8452,5,main]): command REFRESH on channel velux:rollershutter:192_168_170_89:R_D07_2:position will be scheduled.
2023-09-05 15:03:35.978 [DEBUG] [.internal.handler.VeluxBridgeHandler] - handleCommand(velux:rollershutter:192_168_170_89:R_D07_2:position,REFRESH) called.
2023-09-05 15:03:35.979 [DEBUG] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate() [192.168.170.13] GW_OPENHAB_RECEIVEONLY => started => Thread[OH-velux:klf200:192_168_170_89-1,5,main] 
2023-09-05 15:03:35.982 [TRACE] [.internal.handler.VeluxBridgeHandler] - handleCommand(Thread[OH-thingHandler-8452,5,main]) done.
2023-09-05 15:03:35.982 [TRACE] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate() [192.168.170.13] GW_OPENHAB_RECEIVEONLY => receive-only mode => checking messages 
2023-09-05 15:03:35.991 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:rollershutter,limitMaximum) returns enum velux:rollershutter/limitMaximum.
2023-09-05 15:03:35.994 [TRACE] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate() [192.168.170.13] GW_OPENHAB_RECEIVEONLY => no waiting messages => done 
2023-09-05 15:03:35.995 [DEBUG] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate() [192.168.170.13] GW_OPENHAB_RECEIVEONLY => finished => success
2023-09-05 15:03:35.997 [DEBUG] [nal.bridge.VeluxBridgeGetHouseStatus] - evaluateState() finished with failure.
2023-09-05 15:03:35.998 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning true, as item is to be refreshed, now.
2023-09-05 15:03:35.998 [TRACE] [.internal.handler.VeluxBridgeHandler] - getHouseStatusCommsJob(): => GetHouseStatus() => no updates
2023-09-05 15:03:36.000 [TRACE] [.internal.handler.VeluxBridgeHandler] - getHouseStatusCommsJob() initiated by Thread[OH-velux:klf200:192_168_170_89-1,5,main] has finished.
2023-09-05 15:03:36.003 [TRACE] [.internal.handler.VeluxBridgeHandler] - refreshSchedulerJob(): refreshing channel velux:rollershutter:192_168_170_89:_0:limitMaximum.
2023-09-05 15:03:36.004 [TRACE] [.internal.handler.VeluxBridgeHandler] - handleCommand(Thread[OH-thingHandler-8452,5,main]): command REFRESH on channel velux:rollershutter:192_168_170_89:_0:limitMaximum will be scheduled.
2023-09-05 15:03:36.007 [DEBUG] [.internal.handler.VeluxBridgeHandler] - handleCommand(velux:rollershutter:192_168_170_89:_0:limitMaximum,REFRESH) called.
2023-09-05 15:03:36.009 [TRACE] [.internal.handler.VeluxBridgeHandler] - handleCommand(Thread[OH-thingHandler-8452,5,main]) done.
2023-09-05 15:03:36.011 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:rollershutter,position) returns enum velux:rollershutter/position.
2023-09-05 15:03:36.013 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning true, as item is to be refreshed, now.
2023-09-05 15:03:36.015 [TRACE] [.internal.handler.VeluxBridgeHandler] - refreshSchedulerJob(): refreshing channel velux:rollershutter:192_168_170_89:_0:position.
2023-09-05 15:03:36.018 [TRACE] [.internal.handler.VeluxBridgeHandler] - handleCommand(Thread[OH-thingHandler-8452,5,main]): command REFRESH on channel velux:rollershutter:192_168_170_89:_0:position will be scheduled.
2023-09-05 15:03:36.022 [DEBUG] [.internal.handler.VeluxBridgeHandler] - handleCommand(velux:rollershutter:192_168_170_89:_0:position,REFRESH) called.
2023-09-05 15:03:36.023 [TRACE] [.internal.handler.VeluxBridgeHandler] - handleCommand(Thread[OH-thingHandler-8452,5,main]) done.
2023-09-05 15:03:36.024 [TRACE] [.internal.handler.VeluxBridgeHandler] - refreshSchedulerJob(): loop through properties needing a refresh
2023-09-05 15:03:36.026 [TRACE] [binding.velux.internal.VeluxItemType] - getPropertyEntriesByThing(velux:klf200) returns [velux:klf200/firmware, velux:klf200/address, velux:klf200/subnetMask, velux:klf200/defaultGW, velux:klf200/DHCP, velux:klf200/WLANSSID, velux:klf200/WLANPassword, velux:klf200/products, velux:klf200/scenes, velux:klf200/check].
2023-09-05 15:03:36.031 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,firmware) returns enum velux:klf200/firmware.
2023-09-05 15:03:36.033 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:36.035 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,address) returns enum velux:klf200/address.
2023-09-05 15:03:36.036 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:36.037 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,subnetMask) returns enum velux:klf200/subnetMask.
2023-09-05 15:03:36.039 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:36.040 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,defaultGW) returns enum velux:klf200/defaultGW.
2023-09-05 15:03:36.042 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:36.043 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,DHCP) returns enum velux:klf200/DHCP.
2023-09-05 15:03:36.044 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:36.046 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,WLANSSID) returns enum velux:klf200/WLANSSID.
2023-09-05 15:03:36.047 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:36.049 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,WLANPassword) returns enum velux:klf200/WLANPassword.
2023-09-05 15:03:36.050 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:36.052 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,products) returns enum velux:klf200/products.
2023-09-05 15:03:36.053 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:36.055 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,scenes) returns enum velux:klf200/scenes.
2023-09-05 15:03:36.056 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:36.057 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,check) returns enum velux:klf200/check.
2023-09-05 15:03:36.059 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:36.060 [DEBUG] [.internal.handler.VeluxBridgeHandler] - refreshSchedulerJob() initiated by Thread[OH-thingHandler-8452,5,main] finished cycle 18024.
2023-09-05 15:03:36.062 [TRACE] [.internal.handler.VeluxBridgeHandler] - handleCommandCommsJob(Thread[OH-velux:klf200:192_168_170_89-1,5,main]): command REFRESH on channel velux:rollershutter:192_168_170_89:R_D07_2:position.
2023-09-05 15:03:36.063 [DEBUG] [.internal.handler.VeluxBridgeHandler] - handleCommandCommsJob(velux:rollershutter:192_168_170_89:R_D07_2:position,REFRESH) called.
2023-09-05 15:03:36.065 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:rollershutter,position) returns enum velux:rollershutter/position.
2023-09-05 15:03:36.067 [TRACE] [nternal.things.VeluxExistingProducts] - isDirty() returns false.
2023-09-05 15:03:36.068 [TRACE] [.internal.handler.VeluxBridgeHandler] - syncChannelsWithProducts(): no existing products with changed parameters.
2023-09-05 15:03:36.070 [TRACE] [.internal.handler.VeluxBridgeHandler] - handleCommandCommsJob(): work on refresh.
2023-09-05 15:03:36.071 [TRACE] [.internal.handler.VeluxBridgeHandler] - handleCommandCommsJob(): refreshing item velux:rollershutter:192_168_170_89:R_D07_2:position (type velux:rollershutter/position).
2023-09-05 15:03:36.072 [DEBUG] [rnal.handler.ChannelActuatorPosition] - handleRefresh(velux:rollershutter:192_168_170_89:R_D07_2:position,position,org.openhab.binding.velux.internal.handler.VeluxBridgeHandler@1fe580a) called.
2023-09-05 15:03:36.075 [TRACE] [nternal.things.VeluxExistingProducts] - getNoMembers() returns 1.
2023-09-05 15:03:36.082 [TRACE] [nal.handler.utils.ThingConfiguration] - exists(velux:rollershutter:192_168_170_89:R_D07_2:position,serial) returns true.
2023-09-05 15:03:36.084 [TRACE] [nal.handler.utils.ThingConfiguration] - getValue(velux:rollershutter:192_168_170_89:R_D07_2:position,serial) returns 00:00:00:00:00:00:00:00.
2023-09-05 15:03:36.085 [TRACE] [al.handler.utils.Thing2VeluxActuator] - mapThing2Velux(): in velux:rollershutter:192_168_170_89:R_D07_2:position serialNumber=null.
2023-09-05 15:03:36.086 [TRACE] [nal.handler.utils.ThingConfiguration] - exists(velux:rollershutter:192_168_170_89:R_D07_2:position,name) returns true.
2023-09-05 15:03:36.087 [TRACE] [nal.handler.utils.ThingConfiguration] - getValue(velux:rollershutter:192_168_170_89:R_D07_2:position,name) returns 1.
2023-09-05 15:03:36.088 [TRACE] [al.handler.utils.Thing2VeluxActuator] - mapThing2Velux(): in velux:rollershutter:192_168_170_89:R_D07_2:position name=1.
2023-09-05 15:03:36.089 [TRACE] [al.handler.utils.Thing2VeluxActuator] - mapThing2Velux(): in velux:rollershutter:192_168_170_89:R_D07_2:position uniqueIndex=1, proceeding.
2023-09-05 15:03:36.091 [TRACE] [nal.handler.utils.ThingConfiguration] - exists(velux:rollershutter:192_168_170_89:R_D07_2:position,inverted) returns true.
2023-09-05 15:03:36.094 [TRACE] [nal.handler.utils.ThingConfiguration] - getValue(velux:rollershutter:192_168_170_89:R_D07_2:position,inverted) returns false.
2023-09-05 15:03:36.096 [TRACE] [al.handler.utils.Thing2VeluxActuator] - mapThing2Velux(): in velux:rollershutter:192_168_170_89:R_D07_2:position isInverted=false.
2023-09-05 15:03:36.097 [TRACE] [nternal.things.VeluxExistingProducts] - isRegistered(String 1) returns false.
2023-09-05 15:03:36.099 [WARN ] [al.handler.utils.Thing2VeluxActuator] - mapThing2Velux(): actuator with uniqueIndex=1 is not registered
2023-09-05 15:03:36.103 [WARN ] [.internal.handler.VeluxBridgeHandler] - handleCommandCommsJob(velux:rollershutter:192_168_170_89:R_D07_2:position,REFRESH): updating of item velux:rollershutter:192_168_170_89:R_D07_2:position (type velux:rollershutter/position) failed.
2023-09-05 15:03:36.107 [TRACE] [.internal.handler.VeluxBridgeHandler] - handleCommandCommsJob(Thread[OH-velux:klf200:192_168_170_89-1,5,main]) done.
2023-09-05 15:03:36.109 [TRACE] [.internal.handler.VeluxBridgeHandler] - handleCommandCommsJob(Thread[OH-velux:klf200:192_168_170_89-1,5,main]): command REFRESH on channel velux:rollershutter:192_168_170_89:_0:limitMaximum.
2023-09-05 15:03:36.110 [DEBUG] [.internal.handler.VeluxBridgeHandler] - handleCommandCommsJob(velux:rollershutter:192_168_170_89:_0:limitMaximum,REFRESH) called.
2023-09-05 15:03:36.111 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:rollershutter,limitMaximum) returns enum velux:rollershutter/limitMaximum.
2023-09-05 15:03:36.113 [TRACE] [nternal.things.VeluxExistingProducts] - isDirty() returns false.
2023-09-05 15:03:36.114 [TRACE] [.internal.handler.VeluxBridgeHandler] - syncChannelsWithProducts(): no existing products with changed parameters.
2023-09-05 15:03:36.115 [TRACE] [.internal.handler.VeluxBridgeHandler] - handleCommandCommsJob(): work on refresh.
2023-09-05 15:03:36.117 [TRACE] [.internal.handler.VeluxBridgeHandler] - handleCommandCommsJob(): refreshing item velux:rollershutter:192_168_170_89:_0:limitMaximum (type velux:rollershutter/limitMaximum).
2023-09-05 15:03:36.118 [DEBUG] [al.handler.ChannelActuatorLimitation] - handleRefresh(velux:rollershutter:192_168_170_89:_0:limitMaximum,limitMaximum,org.openhab.binding.velux.internal.handler.VeluxBridgeHandler@1fe580a) called.
2023-09-05 15:03:36.119 [TRACE] [nternal.things.VeluxExistingProducts] - getNoMembers() returns 1.
2023-09-05 15:03:36.122 [TRACE] [nal.handler.utils.ThingConfiguration] - exists(velux:rollershutter:192_168_170_89:_0:limitMaximum,serial) returns true.
2023-09-05 15:03:36.124 [TRACE] [nal.handler.utils.ThingConfiguration] - getValue(velux:rollershutter:192_168_170_89:_0:limitMaximum,serial) returns 56:3B:07:26:17:10:32:93.
2023-09-05 15:03:36.127 [TRACE] [nal.handler.utils.ThingConfiguration] - exists(velux:rollershutter:192_168_170_89:_0:limitMaximum,inverted) returns true.
2023-09-05 15:03:36.128 [TRACE] [nal.handler.utils.ThingConfiguration] - getValue(velux:rollershutter:192_168_170_89:_0:limitMaximum,inverted) returns false.
2023-09-05 15:03:36.131 [TRACE] [nternal.things.VeluxExistingProducts] - isRegistered(String 56:3B:07:26:17:10:32:93) returns true.
2023-09-05 15:03:36.135 [TRACE] [nternal.things.VeluxExistingProducts] - get(56:3B:07:26:17:10:32:93) called.
2023-09-05 15:03:36.137 [TRACE] [al.handler.ChannelActuatorLimitation] - handleRefresh(): found actuator VeluxProduct(v2, creator:GW_GET_ALL_NODES_INFORMATION_REQ, dataSource:GATEWAY, name:#0, typeId:SLIDER_SHUTTER, bridgeIndex:0, state:5, serial:56:3B:07:26:17:10:32:93, position:A6A6, target:0000, functionalParameters:null).
2023-09-05 15:03:36.138 [TRACE] [rnal.bridge.VeluxBridgeGetLimitation] - getMaximumLimitation(nodeId=0) called.
2023-09-05 15:03:36.140 [TRACE] [internal.bridge.slip.SlipVeluxBridge] - bridgeAPI() called.
2023-09-05 15:03:36.143 [TRACE] [ng.velux.internal.bridge.VeluxBridge] - bridgeCommunicate(Retrieve Actuator Limitation) called.
2023-09-05 15:03:36.144 [TRACE] [ng.velux.internal.bridge.VeluxBridge] - isAuthenticated() returns true.
2023-09-05 15:03:36.145 [TRACE] [ng.velux.internal.bridge.VeluxBridge] - bridgeCommunicate(Retrieve Actuator Limitation,authenticated) called.
2023-09-05 15:03:36.146 [TRACE] [ng.velux.internal.bridge.VeluxBridge] - isAuthenticated() returns true.
2023-09-05 15:03:36.148 [TRACE] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate(BCP: Retrieve Actuator Limitation, authenticated) called.
2023-09-05 15:03:36.149 [TRACE] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate() 'Retrieve Actuator Limitation', authenticated
2023-09-05 15:03:36.160 [DEBUG] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate() [192.168.170.13] GW_GET_LIMITATION_STATUS_REQ => started => Thread[OH-velux:klf200:192_168_170_89-1,5,main] 
2023-09-05 15:03:36.162 [TRACE] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate() [192.168.170.13] GW_GET_LIMITATION_STATUS_REQ => send mode => preparing command 
2023-09-05 15:03:36.169 [TRACE] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate() [192.168.170.13] GW_GET_LIMITATION_STATUS_REQ => command ready => start sending 
2023-09-05 15:03:36.171 [TRACE] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate() [192.168.170.13] GW_GET_LIMITATION_STATUS_REQ => GW_GET_LIMITATION_STATUS_REQ => sending data => 1A A8 01 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 01
2023-09-05 15:03:36.300 [TRACE] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate() [192.168.170.13] GW_GET_LIMITATION_STATUS_REQ => GW_GET_LIMITATION_STATUS_CFM => received data => 1A A8 01
2023-09-05 15:03:36.301 [TRACE] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate() [192.168.170.13] GW_GET_LIMITATION_STATUS_REQ => GW_GET_LIMITATION_STATUS_CFM => applying data length => 3
2023-09-05 15:03:36.842 [TRACE] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate() [192.168.170.13] GW_GET_LIMITATION_STATUS_REQ => GW_LIMITATION_STATUS_NTF => received data => 1A A8 00 00 F7 FF C8 00 FF FF
2023-09-05 15:03:36.846 [TRACE] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate() [192.168.170.13] GW_GET_LIMITATION_STATUS_REQ => GW_LIMITATION_STATUS_NTF => applying data length => 10
2023-09-05 15:03:36.879 [DEBUG] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate() [192.168.170.13] GW_GET_LIMITATION_STATUS_REQ => finished => success
2023-09-05 15:03:36.882 [TRACE] [internal.things.VeluxProductPosition] - VeluxProductPosition(constructor with 51200 as veluxPosition) called.
2023-09-05 15:03:36.884 [TRACE] [internal.things.VeluxProductPosition] - VeluxProductPosition() created with percent-type 100.
2023-09-05 15:03:36.885 [DEBUG] [rnal.bridge.VeluxBridgeGetLimitation] - getMaximumLimitation() finished successfully.
2023-09-05 15:03:36.891 [DEBUG] [.internal.handler.VeluxBridgeHandler] - handleCommandCommsJob(): updating channel velux:rollershutter:192_168_170_89:_0:limitMaximum to 100.
2023-09-05 15:03:36.898 [TRACE] [.internal.handler.VeluxBridgeHandler] - handleCommandCommsJob(Thread[OH-velux:klf200:192_168_170_89-1,5,main]) done.
2023-09-05 15:03:36.899 [TRACE] [.internal.handler.VeluxBridgeHandler] - handleCommandCommsJob(Thread[OH-velux:klf200:192_168_170_89-1,5,main]): command REFRESH on channel velux:rollershutter:192_168_170_89:_0:position.
2023-09-05 15:03:36.900 [DEBUG] [.internal.handler.VeluxBridgeHandler] - handleCommandCommsJob(velux:rollershutter:192_168_170_89:_0:position,REFRESH) called.
2023-09-05 15:03:36.902 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:rollershutter,position) returns enum velux:rollershutter/position.
2023-09-05 15:03:36.903 [TRACE] [nternal.things.VeluxExistingProducts] - isDirty() returns false.
2023-09-05 15:03:36.904 [TRACE] [.internal.handler.VeluxBridgeHandler] - syncChannelsWithProducts(): no existing products with changed parameters.
2023-09-05 15:03:36.906 [TRACE] [.internal.handler.VeluxBridgeHandler] - handleCommandCommsJob(): work on refresh.
2023-09-05 15:03:36.907 [TRACE] [.internal.handler.VeluxBridgeHandler] - handleCommandCommsJob(): refreshing item velux:rollershutter:192_168_170_89:_0:position (type velux:rollershutter/position).
2023-09-05 15:03:36.908 [DEBUG] [rnal.handler.ChannelActuatorPosition] - handleRefresh(velux:rollershutter:192_168_170_89:_0:position,position,org.openhab.binding.velux.internal.handler.VeluxBridgeHandler@1fe580a) called.
2023-09-05 15:03:36.909 [TRACE] [nternal.things.VeluxExistingProducts] - getNoMembers() returns 1.
2023-09-05 15:03:36.912 [TRACE] [nternal.things.VeluxExistingProducts] - get(0) called.
2023-09-05 15:03:36.913 [TRACE] [internal.bridge.slip.SlipVeluxBridge] - bridgeAPI() called.
2023-09-05 15:03:36.916 [TRACE] [ng.velux.internal.bridge.VeluxBridge] - bridgeCommunicate(Retrieve Product Status) called.
2023-09-05 15:03:36.918 [TRACE] [ng.velux.internal.bridge.VeluxBridge] - isAuthenticated() returns true.
2023-09-05 15:03:36.919 [TRACE] [ng.velux.internal.bridge.VeluxBridge] - bridgeCommunicate(Retrieve Product Status,authenticated) called.
2023-09-05 15:03:36.920 [TRACE] [ng.velux.internal.bridge.VeluxBridge] - isAuthenticated() returns true.
2023-09-05 15:03:36.922 [TRACE] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate(BCP: Retrieve Product Status, authenticated) called.
2023-09-05 15:03:36.923 [TRACE] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate() 'Retrieve Product Status', authenticated
2023-09-05 15:03:36.927 [DEBUG] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate() [192.168.170.13] GW_STATUS_REQUEST_REQ => started => Thread[OH-velux:klf200:192_168_170_89-1,5,main] 
2023-09-05 15:03:36.929 [TRACE] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate() [192.168.170.13] GW_STATUS_REQUEST_REQ => send mode => preparing command 
2023-09-05 15:03:36.936 [TRACE] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate() [192.168.170.13] GW_STATUS_REQUEST_REQ => command ready => start sending 
2023-09-05 15:03:36.938 [TRACE] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate() [192.168.170.13] GW_STATUS_REQUEST_REQ => GW_STATUS_REQUEST_REQ => sending data => 0F C5 01 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 01 F0 00
2023-09-05 15:03:36.968 [TRACE] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate() [192.168.170.13] GW_STATUS_REQUEST_REQ => GW_COMMAND_RUN_STATUS_NTF => received data => 1A A8 01 00 00 A6 A6 00 01 2A 00 00 00
2023-09-05 15:03:36.969 [TRACE] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate() [192.168.170.13] GW_STATUS_REQUEST_REQ => GW_COMMAND_RUN_STATUS_NTF => parallelism allowed => continuing
2023-09-05 15:03:36.992 [TRACE] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate() [192.168.170.13] GW_STATUS_REQUEST_REQ => GW_SESSION_FINISHED_NTF => received data => 1A A8
2023-09-05 15:03:36.994 [TRACE] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate() [192.168.170.13] GW_STATUS_REQUEST_REQ => GW_SESSION_FINISHED_NTF => parallelism allowed => continuing
2023-09-05 15:03:37.069 [TRACE] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate() [192.168.170.13] GW_STATUS_REQUEST_REQ => GW_STATUS_REQUEST_CFM => received data => 0F C5 01
2023-09-05 15:03:37.070 [TRACE] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate() [192.168.170.13] GW_STATUS_REQUEST_REQ => GW_STATUS_REQUEST_CFM => applying data length => 3
2023-09-05 15:03:37.665 [TRACE] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate() [192.168.170.13] GW_STATUS_REQUEST_REQ => GW_STATUS_REQUEST_NTF => received data => 0F C5 01 00 00 01 01 01 00 A6 A6 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
2023-09-05 15:03:37.666 [TRACE] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate() [192.168.170.13] GW_STATUS_REQUEST_REQ => GW_STATUS_REQUEST_NTF => applying data length => 59
2023-09-05 15:03:37.692 [TRACE] [g.velux.internal.things.VeluxProduct] - VeluxProduct(v2, name=unknown) [notification product] created.
2023-09-05 15:03:37.695 [DEBUG] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate() [192.168.170.13] GW_STATUS_REQUEST_REQ => finished => success
2023-09-05 15:03:37.697 [TRACE] [ernal.bridge.slip.SCgetProductStatus] - getProduct(): returning VeluxProduct(v2, creator:GW_STATUS_REQUEST_REQ, dataSource:GATEWAY, name:unknown, typeId:UNDEFTYPE, bridgeIndex:0, state:5, serial:00:00:00:00:00:00:00:00, position:A6A6, target:D400, functionalParameters:null).
2023-09-05 15:03:37.698 [TRACE] [nternal.things.VeluxExistingProducts] - get(0) called.
2023-09-05 15:03:37.700 [TRACE] [nternal.things.VeluxExistingProducts] - isRegistered(ProductBridgeIndex 0) returns true.
2023-09-05 15:03:37.701 [TRACE] [nternal.things.VeluxExistingProducts] - get(0) called.
2023-09-05 15:03:37.703 [DEBUG] [nternal.things.VeluxExistingProducts] - update() newProduct:VeluxProduct(v2, creator:GW_STATUS_REQUEST_REQ, dataSource:GATEWAY, name:unknown, typeId:UNDEFTYPE, bridgeIndex:0, state:5, serial:00:00:00:00:00:00:00:00, position:A6A6, target:D400, functionalParameters:null) (identical)
2023-09-05 15:03:37.704 [DEBUG] [nternal.things.VeluxExistingProducts] - update() theProduct:VeluxProduct(v2, creator:GW_GET_ALL_NODES_INFORMATION_REQ, dataSource:GATEWAY, name:#0, typeId:SLIDER_SHUTTER, bridgeIndex:0, state:5, serial:56:3B:07:26:17:10:32:93, position:A6A6, target:0000, functionalParameters:null) (unchanged)
2023-09-05 15:03:37.706 [TRACE] [nternal.things.VeluxExistingProducts] - get(0) called.
2023-09-05 15:03:37.707 [TRACE] [internal.things.VeluxProductPosition] - VeluxProductPosition(constructor with 42662 as veluxPosition) called.
2023-09-05 15:03:37.709 [TRACE] [internal.things.VeluxProductPosition] - VeluxProductPosition() created with percent-type 83.
2023-09-05 15:03:37.712 [DEBUG] [.internal.handler.VeluxBridgeHandler] - handleCommandCommsJob(): updating channel velux:rollershutter:192_168_170_89:_0:position to 83.
2023-09-05 15:03:37.720 [TRACE] [.internal.handler.VeluxBridgeHandler] - handleCommandCommsJob(Thread[OH-velux:klf200:192_168_170_89-1,5,main]) done.
2023-09-05 15:03:40.196 [TRACE] [rnal.discovery.VeluxDiscoveryService] - stopScan() called.
2023-09-05 15:03:40.198 [TRACE] [rnal.discovery.VeluxDiscoveryService] - stopScan() done.
2023-09-05 15:03:40.200 [TRACE] [rnal.discovery.VeluxDiscoveryService] - startScan() called.
2023-09-05 15:03:40.201 [DEBUG] [rnal.discovery.VeluxDiscoveryService] - startScan(): creating a thing of type binding.
2023-09-05 15:03:40.203 [TRACE] [ng.velux.internal.utils.Localization] - getText() returns Velux Binding Informationselement.
2023-09-05 15:03:40.205 [DEBUG] [rnal.discovery.VeluxDiscoveryService] - startScan(): registering new thing DiscoveryResult [thingUID=velux:binding:org_openhab_binding_velux, properties={bundleVersion=org.openhab.binding.velux_4.0.2 [318]}, representationProperty=bundleVersion, flag=NEW, label=Velux Binding Informationselement, bridgeUID=null, ttl=-1, timestamp=1693919020205].
2023-09-05 15:03:40.208 [DEBUG] [rnal.discovery.VeluxDiscoveryService] - startScan(): VeluxDiscoveryService cannot proceed due to missing Velux bridge(s).
2023-09-05 15:03:40.210 [TRACE] [rnal.discovery.VeluxDiscoveryService] - startScan() done.
2023-09-05 15:03:40.211 [TRACE] [rnal.discovery.VeluxDiscoveryService] - stopScan() called.
2023-09-05 15:03:40.213 [TRACE] [rnal.discovery.VeluxDiscoveryService] - stopScan() done.
2023-09-05 15:03:40.214 [TRACE] [rnal.discovery.VeluxDiscoveryService] - startScan() called.
2023-09-05 15:03:40.216 [DEBUG] [rnal.discovery.VeluxDiscoveryService] - startScan(): creating a thing of type binding.
2023-09-05 15:03:40.218 [TRACE] [ng.velux.internal.utils.Localization] - getText() returns Velux Binding Informationselement.
2023-09-05 15:03:40.219 [DEBUG] [rnal.discovery.VeluxDiscoveryService] - startScan(): registering new thing DiscoveryResult [thingUID=velux:binding:org_openhab_binding_velux, properties={bundleVersion=org.openhab.binding.velux_4.0.2 [318]}, representationProperty=bundleVersion, flag=NEW, label=Velux Binding Informationselement, bridgeUID=null, ttl=-1, timestamp=1693919020219].
2023-09-05 15:03:40.224 [DEBUG] [rnal.discovery.VeluxDiscoveryService] - startScan(): Starting Velux discovery scan for scenes and actuators.
2023-09-05 15:03:40.226 [TRACE] [rnal.discovery.VeluxDiscoveryService] - discoverScenes() called.
2023-09-05 15:03:40.229 [DEBUG] [rnal.discovery.VeluxDiscoveryService] - discoverScenes(): discovering all scenes on bridge velux:klf200:192_168_170_89.
2023-09-05 15:03:40.230 [TRACE] [rnal.discovery.VeluxDiscoveryService] - discoverScenes() finished.
2023-09-05 15:03:40.232 [TRACE] [rnal.discovery.VeluxDiscoveryService] - discoverProducts() called.
2023-09-05 15:03:40.234 [DEBUG] [rnal.discovery.VeluxDiscoveryService] - discoverProducts(): discovering all actuators on bridge velux:klf200:192_168_170_89.
2023-09-05 15:03:40.236 [TRACE] [rnal.discovery.VeluxDiscoveryService] - discoverProducts() found actuator 56:3B:07:26:17:10:32:93 (name #0).
2023-09-05 15:03:40.237 [TRACE] [rnal.discovery.VeluxDiscoveryService] - discoverProducts(): using label _0.
2023-09-05 15:03:40.239 [TRACE] [rnal.discovery.VeluxDiscoveryService] - discoverProducts() dealing with VeluxProduct(v2, creator:GW_GET_ALL_NODES_INFORMATION_REQ, dataSource:GATEWAY, name:#0, typeId:SLIDER_SHUTTER, bridgeIndex:0, state:5, serial:56:3B:07:26:17:10:32:93, position:A6A6, target:0000, functionalParameters:null) (type SLIDER_SHUTTER).
2023-09-05 15:03:40.240 [TRACE] [rnal.discovery.VeluxDiscoveryService] - discoverProducts(): creating rollershutter.
2023-09-05 15:03:40.244 [DEBUG] [rnal.discovery.VeluxDiscoveryService] - discoverProducts(): registering new thing DiscoveryResult [thingUID=velux:rollershutter:192_168_170_89:_0, properties={serial=56:3B:07:26:17:10:32:93, name=#0, inverted=false}, representationProperty=serial, flag=NEW, label=#0, bridgeUID=velux:klf200:192_168_170_89, ttl=-1, timestamp=1693919020243].
2023-09-05 15:03:40.247 [TRACE] [rnal.discovery.VeluxDiscoveryService] - discoverProducts() finished.
2023-09-05 15:03:40.249 [TRACE] [rnal.discovery.VeluxDiscoveryService] - startScan() done.
2023-09-05 15:03:44.724 [DEBUG] [.internal.handler.VeluxBridgeHandler] - refreshSchedulerJob() initiated by Thread[OH-thingHandler-8449,5,main] starting cycle 18025.
2023-09-05 15:03:44.725 [TRACE] [.internal.handler.VeluxBridgeHandler] - refreshSchedulerJob(): processing of possible HSM messages.
2023-09-05 15:03:44.726 [TRACE] [.internal.handler.VeluxBridgeHandler] - refreshSchedulerJob(): loop through all (child things and bridge) linked channels needing a refresh
2023-09-05 15:03:44.727 [TRACE] [elux.internal.handler.BridgeChannels] - getAllLinkedChannelUIDs() returns [velux:rollershutter:192_168_170_89:R_D07_2:position, velux:rollershutter:192_168_170_89:_0:limitMaximum, velux:rollershutter:192_168_170_89:_0:position].
2023-09-05 15:03:44.728 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:rollershutter,position) returns enum velux:rollershutter/position.
2023-09-05 15:03:44.730 [TRACE] [.internal.handler.VeluxBridgeHandler] - getHouseStatusCommsJob() initiated by Thread[OH-velux:klf200:192_168_170_89-1,5,main] will process HouseStatus.
2023-09-05 15:03:44.731 [TRACE] [nal.bridge.VeluxBridgeGetHouseStatus] - evaluateState() called.
2023-09-05 15:03:44.731 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:44.732 [TRACE] [internal.bridge.slip.SlipVeluxBridge] - bridgeAPI() called.
2023-09-05 15:03:44.732 [TRACE] [ng.velux.internal.bridge.VeluxBridge] - bridgeCommunicate(Retrieve House Status) called.
2023-09-05 15:03:44.733 [TRACE] [ng.velux.internal.bridge.VeluxBridge] - isAuthenticated() returns true.
2023-09-05 15:03:44.732 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:rollershutter,limitMaximum) returns enum velux:rollershutter/limitMaximum.
2023-09-05 15:03:44.734 [TRACE] [ng.velux.internal.bridge.VeluxBridge] - bridgeCommunicate(Retrieve House Status,authenticated) called.
2023-09-05 15:03:44.734 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:44.735 [TRACE] [ng.velux.internal.bridge.VeluxBridge] - isAuthenticated() returns true.
2023-09-05 15:03:44.736 [TRACE] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate(BCP: Retrieve House Status, authenticated) called.
2023-09-05 15:03:44.737 [TRACE] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate() 'Retrieve House Status', authenticated
2023-09-05 15:03:44.736 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:rollershutter,position) returns enum velux:rollershutter/position.
2023-09-05 15:03:44.738 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:44.739 [TRACE] [.internal.handler.VeluxBridgeHandler] - refreshSchedulerJob(): loop through properties needing a refresh
2023-09-05 15:03:44.739 [DEBUG] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate() [192.168.170.13] GW_OPENHAB_RECEIVEONLY => started => Thread[OH-velux:klf200:192_168_170_89-1,5,main] 
2023-09-05 15:03:44.740 [TRACE] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate() [192.168.170.13] GW_OPENHAB_RECEIVEONLY => receive-only mode => checking messages 
2023-09-05 15:03:44.740 [TRACE] [binding.velux.internal.VeluxItemType] - getPropertyEntriesByThing(velux:klf200) returns [velux:klf200/firmware, velux:klf200/address, velux:klf200/subnetMask, velux:klf200/defaultGW, velux:klf200/DHCP, velux:klf200/WLANSSID, velux:klf200/WLANPassword, velux:klf200/products, velux:klf200/scenes, velux:klf200/check].
2023-09-05 15:03:44.746 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,firmware) returns enum velux:klf200/firmware.
2023-09-05 15:03:44.749 [TRACE] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate() [192.168.170.13] GW_OPENHAB_RECEIVEONLY => message(s) waiting => start reading 
2023-09-05 15:03:44.748 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:44.752 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,address) returns enum velux:klf200/address.
2023-09-05 15:03:44.755 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:44.758 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,subnetMask) returns enum velux:klf200/subnetMask.
2023-09-05 15:03:44.759 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:44.760 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,defaultGW) returns enum velux:klf200/defaultGW.
2023-09-05 15:03:44.761 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:44.763 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,DHCP) returns enum velux:klf200/DHCP.
2023-09-05 15:03:44.764 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:44.766 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,WLANSSID) returns enum velux:klf200/WLANSSID.
2023-09-05 15:03:44.767 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:44.768 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,WLANPassword) returns enum velux:klf200/WLANPassword.
2023-09-05 15:03:44.770 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:44.774 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,products) returns enum velux:klf200/products.
2023-09-05 15:03:44.776 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:44.777 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,scenes) returns enum velux:klf200/scenes.
2023-09-05 15:03:44.779 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:44.780 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,check) returns enum velux:klf200/check.
2023-09-05 15:03:44.781 [TRACE] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate() [192.168.170.13] GW_OPENHAB_RECEIVEONLY => GW_SESSION_FINISHED_NTF => received data => 0F C5
2023-09-05 15:03:44.782 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:44.783 [TRACE] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate() [192.168.170.13] GW_OPENHAB_RECEIVEONLY => GW_SESSION_FINISHED_NTF => parallelism allowed => continuing
2023-09-05 15:03:44.783 [DEBUG] [.internal.handler.VeluxBridgeHandler] - refreshSchedulerJob() initiated by Thread[OH-thingHandler-8449,5,main] finished cycle 18025.
2023-09-05 15:03:46.073 [DEBUG] [.internal.handler.VeluxBridgeHandler] - refreshSchedulerJob() initiated by Thread[OH-thingHandler-8454,5,main] starting cycle 18026.
2023-09-05 15:03:46.074 [TRACE] [.internal.handler.VeluxBridgeHandler] - refreshSchedulerJob(): processing of possible HSM messages.
2023-09-05 15:03:46.075 [TRACE] [.internal.handler.VeluxBridgeHandler] - refreshSchedulerJob(): loop through all (child things and bridge) linked channels needing a refresh
2023-09-05 15:03:46.077 [TRACE] [elux.internal.handler.BridgeChannels] - getAllLinkedChannelUIDs() returns [velux:rollershutter:192_168_170_89:R_D07_2:position, velux:rollershutter:192_168_170_89:_0:limitMaximum, velux:rollershutter:192_168_170_89:_0:position].
2023-09-05 15:03:46.078 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:rollershutter,position) returns enum velux:rollershutter/position.
2023-09-05 15:03:46.079 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:46.080 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:rollershutter,limitMaximum) returns enum velux:rollershutter/limitMaximum.
2023-09-05 15:03:46.081 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:46.082 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:rollershutter,position) returns enum velux:rollershutter/position.
2023-09-05 15:03:46.083 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:46.084 [TRACE] [.internal.handler.VeluxBridgeHandler] - refreshSchedulerJob(): loop through properties needing a refresh
2023-09-05 15:03:46.085 [TRACE] [binding.velux.internal.VeluxItemType] - getPropertyEntriesByThing(velux:klf200) returns [velux:klf200/firmware, velux:klf200/address, velux:klf200/subnetMask, velux:klf200/defaultGW, velux:klf200/DHCP, velux:klf200/WLANSSID, velux:klf200/WLANPassword, velux:klf200/products, velux:klf200/scenes, velux:klf200/check].
2023-09-05 15:03:46.086 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,firmware) returns enum velux:klf200/firmware.
2023-09-05 15:03:46.087 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:46.088 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,address) returns enum velux:klf200/address.
2023-09-05 15:03:46.089 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:46.091 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,subnetMask) returns enum velux:klf200/subnetMask.
2023-09-05 15:03:46.092 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:46.093 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,defaultGW) returns enum velux:klf200/defaultGW.
2023-09-05 15:03:46.094 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:46.096 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,DHCP) returns enum velux:klf200/DHCP.
2023-09-05 15:03:46.097 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:46.098 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,WLANSSID) returns enum velux:klf200/WLANSSID.
2023-09-05 15:03:46.100 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:46.101 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,WLANPassword) returns enum velux:klf200/WLANPassword.
2023-09-05 15:03:46.102 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:46.104 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,products) returns enum velux:klf200/products.
2023-09-05 15:03:46.105 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:46.106 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,scenes) returns enum velux:klf200/scenes.
2023-09-05 15:03:46.107 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:46.108 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,check) returns enum velux:klf200/check.
2023-09-05 15:03:46.109 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:46.111 [DEBUG] [.internal.handler.VeluxBridgeHandler] - refreshSchedulerJob() initiated by Thread[OH-thingHandler-8454,5,main] finished cycle 18026.
2023-09-05 15:03:47.914 [DEBUG] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate() [192.168.170.13] GW_OPENHAB_RECEIVEONLY => finished => success
2023-09-05 15:03:47.916 [DEBUG] [nal.bridge.VeluxBridgeGetHouseStatus] - evaluateState() finished with failure.
2023-09-05 15:03:47.917 [TRACE] [.internal.handler.VeluxBridgeHandler] - getHouseStatusCommsJob(): => GetHouseStatus() => no updates
2023-09-05 15:03:47.918 [TRACE] [.internal.handler.VeluxBridgeHandler] - getHouseStatusCommsJob() initiated by Thread[OH-velux:klf200:192_168_170_89-1,5,main] has finished.
2023-09-05 15:03:47.923 [TRACE] [.internal.handler.VeluxBridgeHandler] - getHouseStatusCommsJob() initiated by Thread[OH-velux:klf200:192_168_170_89-1,5,main] will process HouseStatus.
2023-09-05 15:03:47.924 [TRACE] [nal.bridge.VeluxBridgeGetHouseStatus] - evaluateState() called.
2023-09-05 15:03:47.926 [TRACE] [internal.bridge.slip.SlipVeluxBridge] - bridgeAPI() called.
2023-09-05 15:03:47.927 [TRACE] [ng.velux.internal.bridge.VeluxBridge] - bridgeCommunicate(Retrieve House Status) called.
2023-09-05 15:03:47.929 [TRACE] [ng.velux.internal.bridge.VeluxBridge] - isAuthenticated() returns true.
2023-09-05 15:03:47.930 [TRACE] [ng.velux.internal.bridge.VeluxBridge] - bridgeCommunicate(Retrieve House Status,authenticated) called.
2023-09-05 15:03:47.932 [TRACE] [ng.velux.internal.bridge.VeluxBridge] - isAuthenticated() returns true.
2023-09-05 15:03:47.933 [TRACE] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate(BCP: Retrieve House Status, authenticated) called.
2023-09-05 15:03:47.934 [TRACE] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate() 'Retrieve House Status', authenticated
2023-09-05 15:03:47.937 [DEBUG] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate() [192.168.170.13] GW_OPENHAB_RECEIVEONLY => started => Thread[OH-velux:klf200:192_168_170_89-1,5,main] 
2023-09-05 15:03:47.938 [TRACE] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate() [192.168.170.13] GW_OPENHAB_RECEIVEONLY => receive-only mode => checking messages 
2023-09-05 15:03:47.948 [TRACE] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate() [192.168.170.13] GW_OPENHAB_RECEIVEONLY => no waiting messages => done 
2023-09-05 15:03:47.949 [DEBUG] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate() [192.168.170.13] GW_OPENHAB_RECEIVEONLY => finished => success
2023-09-05 15:03:47.950 [DEBUG] [nal.bridge.VeluxBridgeGetHouseStatus] - evaluateState() finished with failure.
2023-09-05 15:03:47.952 [TRACE] [.internal.handler.VeluxBridgeHandler] - getHouseStatusCommsJob(): => GetHouseStatus() => no updates
2023-09-05 15:03:47.953 [TRACE] [.internal.handler.VeluxBridgeHandler] - getHouseStatusCommsJob() initiated by Thread[OH-velux:klf200:192_168_170_89-1,5,main] has finished.
2023-09-05 15:03:54.784 [DEBUG] [.internal.handler.VeluxBridgeHandler] - refreshSchedulerJob() initiated by Thread[OH-thingHandler-8449,5,main] starting cycle 18027.
2023-09-05 15:03:54.785 [TRACE] [.internal.handler.VeluxBridgeHandler] - refreshSchedulerJob(): processing of possible HSM messages.
2023-09-05 15:03:54.787 [TRACE] [.internal.handler.VeluxBridgeHandler] - refreshSchedulerJob(): loop through all (child things and bridge) linked channels needing a refresh
2023-09-05 15:03:54.787 [TRACE] [.internal.handler.VeluxBridgeHandler] - getHouseStatusCommsJob() initiated by Thread[OH-velux:klf200:192_168_170_89-1,5,main] will process HouseStatus.
2023-09-05 15:03:54.788 [TRACE] [nal.bridge.VeluxBridgeGetHouseStatus] - evaluateState() called.
2023-09-05 15:03:54.788 [TRACE] [elux.internal.handler.BridgeChannels] - getAllLinkedChannelUIDs() returns [velux:rollershutter:192_168_170_89:R_D07_2:position, velux:rollershutter:192_168_170_89:_0:limitMaximum, velux:rollershutter:192_168_170_89:_0:position].
2023-09-05 15:03:54.789 [TRACE] [internal.bridge.slip.SlipVeluxBridge] - bridgeAPI() called.
2023-09-05 15:03:54.789 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:rollershutter,position) returns enum velux:rollershutter/position.
2023-09-05 15:03:54.790 [TRACE] [ng.velux.internal.bridge.VeluxBridge] - bridgeCommunicate(Retrieve House Status) called.
2023-09-05 15:03:54.790 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:54.791 [TRACE] [ng.velux.internal.bridge.VeluxBridge] - isAuthenticated() returns true.
2023-09-05 15:03:54.791 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:rollershutter,limitMaximum) returns enum velux:rollershutter/limitMaximum.
2023-09-05 15:03:54.792 [TRACE] [ng.velux.internal.bridge.VeluxBridge] - bridgeCommunicate(Retrieve House Status,authenticated) called.
2023-09-05 15:03:54.792 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:54.794 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:rollershutter,position) returns enum velux:rollershutter/position.
2023-09-05 15:03:54.794 [TRACE] [ng.velux.internal.bridge.VeluxBridge] - isAuthenticated() returns true.
2023-09-05 15:03:54.795 [TRACE] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate(BCP: Retrieve House Status, authenticated) called.
2023-09-05 15:03:54.795 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:54.797 [TRACE] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate() 'Retrieve House Status', authenticated
2023-09-05 15:03:54.797 [TRACE] [.internal.handler.VeluxBridgeHandler] - refreshSchedulerJob(): loop through properties needing a refresh
2023-09-05 15:03:54.799 [TRACE] [binding.velux.internal.VeluxItemType] - getPropertyEntriesByThing(velux:klf200) returns [velux:klf200/firmware, velux:klf200/address, velux:klf200/subnetMask, velux:klf200/defaultGW, velux:klf200/DHCP, velux:klf200/WLANSSID, velux:klf200/WLANPassword, velux:klf200/products, velux:klf200/scenes, velux:klf200/check].
2023-09-05 15:03:54.800 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,firmware) returns enum velux:klf200/firmware.
2023-09-05 15:03:54.800 [DEBUG] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate() [192.168.170.13] GW_OPENHAB_RECEIVEONLY => started => Thread[OH-velux:klf200:192_168_170_89-1,5,main] 
2023-09-05 15:03:54.802 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:54.802 [TRACE] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate() [192.168.170.13] GW_OPENHAB_RECEIVEONLY => receive-only mode => checking messages 
2023-09-05 15:03:54.803 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,address) returns enum velux:klf200/address.
2023-09-05 15:03:54.805 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:54.806 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,subnetMask) returns enum velux:klf200/subnetMask.
2023-09-05 15:03:54.808 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:54.809 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,defaultGW) returns enum velux:klf200/defaultGW.
2023-09-05 15:03:54.810 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:54.812 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,DHCP) returns enum velux:klf200/DHCP.
2023-09-05 15:03:54.813 [TRACE] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate() [192.168.170.13] GW_OPENHAB_RECEIVEONLY => no waiting messages => done 
2023-09-05 15:03:54.813 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:54.814 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,WLANSSID) returns enum velux:klf200/WLANSSID.
2023-09-05 15:03:54.814 [DEBUG] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate() [192.168.170.13] GW_OPENHAB_RECEIVEONLY => finished => success
2023-09-05 15:03:54.816 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:54.816 [DEBUG] [nal.bridge.VeluxBridgeGetHouseStatus] - evaluateState() finished with failure.
2023-09-05 15:03:54.818 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,WLANPassword) returns enum velux:klf200/WLANPassword.
2023-09-05 15:03:54.818 [TRACE] [.internal.handler.VeluxBridgeHandler] - getHouseStatusCommsJob(): => GetHouseStatus() => no updates
2023-09-05 15:03:54.819 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:54.819 [TRACE] [.internal.handler.VeluxBridgeHandler] - getHouseStatusCommsJob() initiated by Thread[OH-velux:klf200:192_168_170_89-1,5,main] has finished.
2023-09-05 15:03:54.820 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,products) returns enum velux:klf200/products.
2023-09-05 15:03:54.822 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:54.824 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,scenes) returns enum velux:klf200/scenes.
2023-09-05 15:03:54.825 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:54.826 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,check) returns enum velux:klf200/check.
2023-09-05 15:03:54.827 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:54.829 [DEBUG] [.internal.handler.VeluxBridgeHandler] - refreshSchedulerJob() initiated by Thread[OH-thingHandler-8449,5,main] finished cycle 18027.
2023-09-05 15:03:56.112 [DEBUG] [.internal.handler.VeluxBridgeHandler] - refreshSchedulerJob() initiated by Thread[OH-thingHandler-8454,5,main] starting cycle 18028.
2023-09-05 15:03:56.113 [TRACE] [.internal.handler.VeluxBridgeHandler] - refreshSchedulerJob(): processing of possible HSM messages.
2023-09-05 15:03:56.114 [TRACE] [.internal.handler.VeluxBridgeHandler] - refreshSchedulerJob(): loop through all (child things and bridge) linked channels needing a refresh
2023-09-05 15:03:56.114 [TRACE] [.internal.handler.VeluxBridgeHandler] - getHouseStatusCommsJob() initiated by Thread[OH-velux:klf200:192_168_170_89-1,5,main] will process HouseStatus.
2023-09-05 15:03:56.115 [TRACE] [nal.bridge.VeluxBridgeGetHouseStatus] - evaluateState() called.
2023-09-05 15:03:56.115 [TRACE] [elux.internal.handler.BridgeChannels] - getAllLinkedChannelUIDs() returns [velux:rollershutter:192_168_170_89:R_D07_2:position, velux:rollershutter:192_168_170_89:_0:limitMaximum, velux:rollershutter:192_168_170_89:_0:position].
2023-09-05 15:03:56.116 [TRACE] [internal.bridge.slip.SlipVeluxBridge] - bridgeAPI() called.
2023-09-05 15:03:56.116 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:rollershutter,position) returns enum velux:rollershutter/position.
2023-09-05 15:03:56.116 [TRACE] [ng.velux.internal.bridge.VeluxBridge] - bridgeCommunicate(Retrieve House Status) called.
2023-09-05 15:03:56.116 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:56.117 [TRACE] [ng.velux.internal.bridge.VeluxBridge] - isAuthenticated() returns true.
2023-09-05 15:03:56.117 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:rollershutter,limitMaximum) returns enum velux:rollershutter/limitMaximum.
2023-09-05 15:03:56.118 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:56.119 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:rollershutter,position) returns enum velux:rollershutter/position.
2023-09-05 15:03:56.120 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:56.122 [TRACE] [.internal.handler.VeluxBridgeHandler] - refreshSchedulerJob(): loop through properties needing a refresh
2023-09-05 15:03:56.123 [TRACE] [binding.velux.internal.VeluxItemType] - getPropertyEntriesByThing(velux:klf200) returns [velux:klf200/firmware, velux:klf200/address, velux:klf200/subnetMask, velux:klf200/defaultGW, velux:klf200/DHCP, velux:klf200/WLANSSID, velux:klf200/WLANPassword, velux:klf200/products, velux:klf200/scenes, velux:klf200/check].
2023-09-05 15:03:56.118 [TRACE] [ng.velux.internal.bridge.VeluxBridge] - bridgeCommunicate(Retrieve House Status,authenticated) called.
2023-09-05 15:03:56.124 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,firmware) returns enum velux:klf200/firmware.
2023-09-05 15:03:56.125 [TRACE] [ng.velux.internal.bridge.VeluxBridge] - isAuthenticated() returns true.
2023-09-05 15:03:56.126 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:56.126 [TRACE] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate(BCP: Retrieve House Status, authenticated) called.
2023-09-05 15:03:56.127 [TRACE] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate() 'Retrieve House Status', authenticated
2023-09-05 15:03:56.127 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,address) returns enum velux:klf200/address.
2023-09-05 15:03:56.128 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:56.129 [DEBUG] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate() [192.168.170.13] GW_OPENHAB_RECEIVEONLY => started => Thread[OH-velux:klf200:192_168_170_89-1,5,main] 
2023-09-05 15:03:56.129 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,subnetMask) returns enum velux:klf200/subnetMask.
2023-09-05 15:03:56.130 [TRACE] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate() [192.168.170.13] GW_OPENHAB_RECEIVEONLY => receive-only mode => checking messages 
2023-09-05 15:03:56.131 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:56.132 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,defaultGW) returns enum velux:klf200/defaultGW.
2023-09-05 15:03:56.133 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:56.135 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,DHCP) returns enum velux:klf200/DHCP.
2023-09-05 15:03:56.136 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:56.138 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,WLANSSID) returns enum velux:klf200/WLANSSID.
2023-09-05 15:03:56.139 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:56.140 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,WLANPassword) returns enum velux:klf200/WLANPassword.
2023-09-05 15:03:56.141 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:56.143 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,products) returns enum velux:klf200/products.
2023-09-05 15:03:56.143 [TRACE] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate() [192.168.170.13] GW_OPENHAB_RECEIVEONLY => no waiting messages => done 
2023-09-05 15:03:56.144 [DEBUG] [internal.bridge.slip.SlipVeluxBridge] - bridgeDirectCommunicate() [192.168.170.13] GW_OPENHAB_RECEIVEONLY => finished => success
2023-09-05 15:03:56.144 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:56.144 [DEBUG] [nal.bridge.VeluxBridgeGetHouseStatus] - evaluateState() finished with failure.
2023-09-05 15:03:56.145 [TRACE] [.internal.handler.VeluxBridgeHandler] - getHouseStatusCommsJob(): => GetHouseStatus() => no updates
2023-09-05 15:03:56.145 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,scenes) returns enum velux:klf200/scenes.
2023-09-05 15:03:56.146 [TRACE] [.internal.handler.VeluxBridgeHandler] - getHouseStatusCommsJob() initiated by Thread[OH-velux:klf200:192_168_170_89-1,5,main] has finished.
2023-09-05 15:03:56.146 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:56.147 [TRACE] [binding.velux.internal.VeluxItemType] - getByThingAndChannel(velux:klf200,check) returns enum velux:klf200/check.
2023-09-05 15:03:56.149 [TRACE] [binding.velux.internal.VeluxItemType] - isToBeRefreshedNow(): returning false, as refresh cycle has not yet come for this item.
2023-09-05 15:03:56.150 [DEBUG] [.internal.handler.VeluxBridgeHandler] - refreshSchedulerJob() initiated by Thread[OH-thingHandler-8454,5,main] finished cycle 18028.