I am trying to use a leakSMART water sensor with a Nortek HUSBZB-1 controller using the openHAB ZigBee Binding Version 3.0.1 on a Raspberry Pi 3B+ (2017), however, during device discovery the channel that I imagine would be available for water sensing is not discovered.
Upon pairing the leakSMART device I see channels for Battery level, Temperature, Battery Alarm and Battery Voltage, but nothing related to a water sensing contact or switch.
I am able to use the device in conjunction with SmartThings, but would prefer to use it directly with a local controller and openHAB rather than get updates from SmartThings.
Here is a picture of the Thing after pairing it with my HUSBZB-1:
2021-04-15_13-51-43|333x500
The Channels that are discovered:
A log file that I recorded after restarting my openHAB server to try and get some discovery information for the device:
14:39:34.018 [DEBUG] [.transaction.ZigBeeTransactionManager] - 2BC2/1: Sending ZigBeeTransaction [ieeeAddress=7C2524000015C6BB queueTime=119, state=WAITING, sendCnt=0, command=DefaultResponse [Level Control: 0000/0 -> 2BC2/1, cluster=0008, TID=6C, commandIdentifier=7, statusCode=SUCCESS]]
14:39:34.020 [DEBUG] [.transaction.ZigBeeTransactionManager] - transactionListenerAdded: 5 outstanding
14:39:34.022 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX CMD: DefaultResponse [Level Control: 0000/0 -> 2BC2/1, cluster=0008, TID=6C, commandIdentifier=7, statusCode=SUCCESS]
14:39:34.024 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=CLIENT_TO_SERVER, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=108, commandId=11]
14:39:34.027 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0000/1, destinationAddress=2BC2/1, profile=0104, cluster=0008, addressMode=DEVICE, radius=8, apsSecurity=false, ackRequest=true, apsCounter=A6, rssi=--, lqi=--, payload=10 6C 0B 07 00]
14:39:34.029 [DEBUG] [.zigbee.transaction.ZigBeeTransaction] - Transaction state changed: nwk=2BC2, TID=69, event=RX_ACK, state=COMPLETE
14:39:34.032 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspMessageSentHandler [networkId=0, type=EMBER_OUTGOING_DIRECT, indexOrDestination=6DF4, apsFrame=EmberApsFrame [profileId=0104, clusterId=0008, sourceEndpoint=1, destinationEndpoint=1, options=[EMBER_APS_OPTION_RETRY, EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=F3], messageTag=6B, status=EMBER_SUCCESS, messageContents=]
14:39:34.052 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspSendUnicastResponse [networkId=0, status=EMBER_SUCCESS, sequence=F4]
14:39:34.052 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX STA: msgTag=6B state=RX_ACK
14:39:34.055 [DEBUG] [.transaction.ZigBeeTransactionManager] - notifyTransactionProgress: TID=6B, state=RX_ACK, outstanding=5
14:39:34.057 [DEBUG] [.transaction.ZigBeeTransactionManager] - Transaction complete: ZigBeeTransaction [ieeeAddress=7C2524000016B44E queueTime=500, state=COMPLETE, sendCnt=1, command=DefaultResponse [Level Control: 0000/0 -> 6DF4/1, cluster=0008, TID=6B, commandIdentifier=1, statusCode=SUCCESS]]
14:39:34.060 [DEBUG] [.transaction.ZigBeeTransactionManager] - transactionListenerRemoved: 4 outstanding
14:39:34.062 [DEBUG] [ee.transaction.ZigBeeTransactionQueue] - 7C2524000016B44E: transactionComplete, state=COMPLETE, outstanding=0
14:39:34.064 [DEBUG] [.transaction.ZigBeeTransactionManager] - Transaction Manager: Send Next transaction. outstandingTransactions=4, outstandingQueues=5, sleepy=1/3
14:39:34.066 [DEBUG] [.transaction.ZigBeeTransactionManager] - 6DF4/1: Sending ZigBeeTransaction [ieeeAddress=7C2524000016B44E queueTime=123, state=WAITING, sendCnt=0, command=ConfigureReportingCommand [Level Control: 0000/0 -> 6DF4/1, cluster=0008, TID=6E, records=[AttributeReportingConfigurationRecord [attributeDataType=UNSIGNED_8_BIT_INTEGER, attributeIdentifier=0, direction=0, minimumReportingInterval=1, maximumReportingInterval=900, reportableChange=1]]]]
14:39:34.068 [DEBUG] [.transaction.ZigBeeTransactionManager] - transactionListenerAdded: 5 outstanding
14:39:34.071 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ConfigureReportingCommand [Level Control: 0000/0 -> 6DF4/1, cluster=0008, TID=6E, records=[AttributeReportingConfigurationRecord [attributeDataType=UNSIGNED_8_BIT_INTEGER, attributeIdentifier=0, direction=0, minimumReportingInterval=1, maximumReportingInterval=900, reportableChange=1]]]
14:39:34.073 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=CLIENT_TO_SERVER, disableDefaultResponse=false, manufacturerCode=0, sequenceNumber=110, commandId=6]
14:39:34.075 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0000/1, destinationAddress=6DF4/1, profile=0104, cluster=0008, addressMode=DEVICE, radius=8, apsSecurity=false, ackRequest=true, apsCounter=A7, rssi=--, lqi=--, payload=00 6E 06 00 00 00 20 01 00 84 03 01]
14:39:34.078 [DEBUG] [.zigbee.transaction.ZigBeeTransaction] - Transaction state changed: nwk=6DF4, TID=6B, event=RX_ACK, state=COMPLETE
14:39:34.083 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspMessageSentHandler [networkId=0, type=EMBER_OUTGOING_DIRECT, indexOrDestination=2BC2, apsFrame=EmberApsFrame [profileId=0104, clusterId=0008, sourceEndpoint=1, destinationEndpoint=1, options=[EMBER_APS_OPTION_RETRY, EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=F4], messageTag=6C, status=EMBER_SUCCESS, messageContents=]
14:39:34.104 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspSendUnicastResponse [networkId=0, status=EMBER_SUCCESS, sequence=F5]
14:39:34.104 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX STA: msgTag=6C state=RX_ACK
14:39:34.107 [DEBUG] [.transaction.ZigBeeTransactionManager] - notifyTransactionProgress: TID=6C, state=RX_ACK, outstanding=5
14:39:34.110 [DEBUG] [.transaction.ZigBeeTransactionManager] - Transaction complete: ZigBeeTransaction [ieeeAddress=7C2524000015C6BB queueTime=211, state=COMPLETE, sendCnt=1, command=DefaultResponse [Level Control: 0000/0 -> 2BC2/1, cluster=0008, TID=6C, commandIdentifier=7, statusCode=SUCCESS]]
14:39:34.112 [DEBUG] [.transaction.ZigBeeTransactionManager] - transactionListenerRemoved: 4 outstanding
14:39:34.114 [DEBUG] [ee.transaction.ZigBeeTransactionQueue] - 7C2524000015C6BB: transactionComplete, state=COMPLETE, outstanding=0
14:39:34.116 [DEBUG] [.transaction.ZigBeeTransactionManager] - Transaction Manager: Send Next transaction. outstandingTransactions=4, outstandingQueues=5, sleepy=1/3
14:39:34.118 [DEBUG] [.transaction.ZigBeeTransactionManager] - 2BC2/0: Sending ZigBeeTransaction [ieeeAddress=7C2524000015C6BB queueTime=148, state=WAITING, sendCnt=0, command=ManagementBindRequest [0000/0 -> 2BC2/0, cluster=0033, TID=6F, startIndex=0]]
14:39:34.120 [DEBUG] [.transaction.ZigBeeTransactionManager] - transactionListenerAdded: 5 outstanding
14:39:34.122 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ManagementBindRequest [0000/0 -> 2BC2/0, cluster=0033, TID=6F, startIndex=0]
14:39:34.126 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0000/0, destinationAddress=2BC2/0, profile=0000, cluster=0033, addressMode=DEVICE, radius=8, apsSecurity=false, ackRequest=true, apsCounter=A8, rssi=--, lqi=--, payload=6F 00]
14:39:34.141 [DEBUG] [.zigbee.transaction.ZigBeeTransaction] - Transaction state changed: nwk=2BC2, TID=6C, event=RX_ACK, state=COMPLETE
14:39:34.158 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspSendUnicastResponse [networkId=0, status=EMBER_SUCCESS, sequence=F6]
14:39:34.209 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspMessageSentHandler [networkId=0, type=EMBER_OUTGOING_DIRECT, indexOrDestination=2BC2, apsFrame=EmberApsFrame [profileId=0000, clusterId=0033, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_RETRY, EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=F6], messageTag=6F, status=EMBER_SUCCESS, messageContents=]
14:39:34.211 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX STA: msgTag=6F state=RX_ACK
14:39:34.213 [DEBUG] [.transaction.ZigBeeTransactionManager] - notifyTransactionProgress: TID=6F, state=RX_ACK, outstanding=5
14:39:34.215 [DEBUG] [.zigbee.transaction.ZigBeeTransaction] - Transaction state changed: nwk=2BC2, TID=6F, event=RX_ACK, state=ACKED
14:39:34.230 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspMessageSentHandler [networkId=0, type=EMBER_OUTGOING_DIRECT, indexOrDestination=6DF4, apsFrame=EmberApsFrame [profileId=0104, clusterId=0008, sourceEndpoint=1, destinationEndpoint=1, options=[EMBER_APS_OPTION_RETRY, EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=F5], messageTag=6E, status=EMBER_SUCCESS, messageContents=]
14:39:34.232 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX STA: msgTag=6E state=RX_ACK
14:39:34.234 [DEBUG] [.transaction.ZigBeeTransactionManager] - notifyTransactionProgress: TID=6E, state=RX_ACK, outstanding=5
14:39:34.236 [DEBUG] [.zigbee.transaction.ZigBeeTransaction] - Transaction state changed: nwk=6DF4, TID=6E, event=RX_ACK, state=ACKED
14:39:34.253 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingMessageHandler [networkId=0, type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=0000, clusterId=8033, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_RETRY], groupId=0, sequence=89], lastHopLqi=255, lastHopRssi=-58, sender=2BC2, bindingIndex=255, addressIndex=255, messageContents=6F 00 02 00 02 BB C6 15 00 00 24 25 7C 01 08 00 03 CF 35 3D 0B 00 6F 0D 00 01 BB C6 15 00 00 24 25 7C 01 06 00 03 CF 35 3D 0B 00 6F 0D 00 01]
14:39:34.256 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=2BC2/0, destinationAddress=0000/0, profile=0000, cluster=8033, addressMode=DEVICE, radius=0, apsSecurity=false, ackRequest=false, apsCounter=89, rssi=-58, lqi=FF, payload=6F 00 02 00 02 BB C6 15 00 00 24 25 7C 01 08 00 03 CF 35 3D 0B 00 6F 0D 00 01 BB C6 15 00 00 24 25 7C 01 06 00 03 CF 35 3D 0B 00 6F 0D 00 01]
14:39:34.258 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ManagementBindResponse [2BC2/0 -> 0000/0, cluster=8033, TID=6F, status=SUCCESS, bindingTableEntries=2, startIndex=0, bindingTableList=[BindingTable [srcAddr=7C2524000015C6BB/1, dstAddr=000D6F000B3D35CF/1, clusterId=0008], BindingTable [srcAddr=7C2524000015C6BB/1, dstAddr=000D6F000B3D35CF/1, clusterId=0006]]]
14:39:34.261 [DEBUG] [.transaction.ZigBeeTransactionManager] - notifyTransactionCommand: ManagementBindResponse [2BC2/0 -> 0000/0, cluster=8033, TID=6F, status=SUCCESS, bindingTableEntries=2, startIndex=0, bindingTableList=[BindingTable [srcAddr=7C2524000015C6BB/1, dstAddr=000D6F000B3D35CF/1, clusterId=0008], BindingTable [srcAddr=7C2524000015C6BB/1, dstAddr=000D6F000B3D35CF/1, clusterId=0006]]]
14:39:34.266 [DEBUG] [com.zsmartsystems.zigbee.ZigBeeNode ] - 7C2524000015C6BB: Binding table updated: [BindingTable [srcAddr=7C2524000015C6BB/1, dstAddr=000D6F000B3D35CF/1, clusterId=0008], BindingTable [srcAddr=7C2524000015C6BB/1, dstAddr=000D6F000B3D35CF/1, clusterId=0006]]
14:39:34.266 [DEBUG] [.transaction.ZigBeeTransactionManager] - Transaction complete: ZigBeeTransaction [ieeeAddress=7C2524000015C6BB queueTime=296, state=COMPLETE, sendCnt=1, command=ManagementBindRequest [0000/0 -> 2BC2/0, cluster=0033, TID=6F, startIndex=0]]
14:39:34.274 [INFO ] [hab.event.ThingStatusInfoChangedEvent] - Thing 'zigbee:device:husbzb_zigbee:7c2524000015c6bb' changed from UNKNOWN to ONLINE
14:39:34.272 [DEBUG] [ing.zigbee.handler.ZigBeeThingHandler] - 7C2524000015C6BB: Polling initialised at 916360ms
14:39:34.279 [DEBUG] [ing.zigbee.handler.ZigBeeThingHandler] - 7C2524000015C6BB: Done initialising ZigBee Thing handler
14:39:34.281 [DEBUG] [database.ZigBeeNetworkDatabaseManager] - 7C2524000015C6BB: Data store: Deferring write for 250ms.
14:39:34.271 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingMessageHandler [networkId=0, type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=0104, clusterId=0008, sourceEndpoint=1, destinationEndpoint=1, options=[EMBER_APS_OPTION_RETRY], groupId=0, sequence=C9], lastHopLqi=255, lastHopRssi=-54, sender=6DF4, bindingIndex=255, addressIndex=255, messageContents=08 6E 07 00]
14:39:34.282 [INFO ] [openhab.event.FirmwareStatusInfoEvent] - Firmware status of thing zigbee:device:husbzb_zigbee:7c2524000015c6bb changed to UNKNOWN.
14:39:34.275 [DEBUG] [.transaction.ZigBeeTransactionManager] - transactionListenerRemoved: 4 outstanding
14:39:34.288 [DEBUG] [ee.transaction.ZigBeeTransactionQueue] - 7C2524000015C6BB: transactionComplete, state=COMPLETE, outstanding=0
14:39:34.290 [DEBUG] [.transaction.ZigBeeTransactionManager] - Transaction Manager: Send Next transaction. outstandingTransactions=4, outstandingQueues=4, sleepy=1/3
14:39:34.286 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=6DF4/1, destinationAddress=0000/1, profile=0104, cluster=0008, addressMode=DEVICE, radius=0, apsSecurity=false, ackRequest=false, apsCounter=C9, rssi=-54, lqi=FF, payload=08 6E 07 00]
14:39:34.285 [DEBUG] [g.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker reset for handler with thingUID=zigbee:device:husbzb_zigbee:7c2524000015c6bb
14:39:34.294 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=false, manufacturerCode=0, sequenceNumber=110, commandId=7]
14:39:34.295 [DEBUG] [g.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker cancelled task for thingUID=zigbee:device:husbzb_zigbee:7c2524000015c6bb
14:39:34.296 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ConfigureReportingResponse [Level Control: 6DF4/1 -> 0000/1, cluster=0008, TID=6E, status=SUCCESS, records=null]
14:39:34.297 [DEBUG] [g.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker scheduled task for thingUID=zigbee:device:husbzb_zigbee:7c2524000015c6bb in 1830 seconds
14:39:34.297 [DEBUG] [.transaction.ZigBeeTransactionManager] - notifyTransactionCommand: ConfigureReportingResponse [Level Control: 6DF4/1 -> 0000/1, cluster=0008, TID=6E, status=SUCCESS, records=null]
14:39:34.298 [DEBUG] [.converter.ZigBeeConverterSwitchLevel] - 7C2524000015C6BB: ZigBee attribute reports ZclAttribute [cluster=On/Off, id=0, name=On Off, dataType=BOOLEAN, lastValue=false, lastReportTime=Thu Apr 15 14:39:33 MST 2021, implemented=false]
14:39:34.306 [DEBUG] [.converter.ZigBeeBaseChannelConverter] - 7C2524000015C6BB: Channel zigbee:device:husbzb_zigbee:7c2524000015c6bb:7C2524000015C6BB_1_dimmer updated to OFF
14:39:34.308 [DEBUG] [ing.zigbee.handler.ZigBeeThingHandler] - 7C2524000015C6BB: Updating ZigBee channel state zigbee:device:husbzb_zigbee:7c2524000015c6bb:7C2524000015C6BB_1_dimmer to OFF
14:39:34.311 [DEBUG] [g.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker reset for handler with thingUID=zigbee:device:husbzb_zigbee:7c2524000015c6bb
14:39:34.313 [DEBUG] [g.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker cancelled task for thingUID=zigbee:device:husbzb_zigbee:7c2524000015c6bb
14:39:34.315 [DEBUG] [g.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker scheduled task for thingUID=zigbee:device:husbzb_zigbee:7c2524000015c6bb in 1830 seconds
14:39:34.317 [DEBUG] [.converter.ZigBeeConverterSwitchLevel] - 7C2524000015C6BB: ZigBee attribute reports ZclAttribute [cluster=Level Control, id=0, name=Current Level, dataType=UNSIGNED_8_BIT_INTEGER, lastValue=254, lastReportTime=Thu Apr 15 14:39:32 MST 2021, implemented=true]
14:39:34.318 [DEBUG] [.transaction.ZigBeeTransactionManager] - Transaction complete: ZigBeeTransaction [ieeeAddress=7C2524000016B44E queueTime=374, state=COMPLETE, sendCnt=1, command=ConfigureReportingCommand [Level Control: 0000/0 -> 6DF4/1, cluster=0008, TID=6E, records=[AttributeReportingConfigurationRecord [attributeDataType=UNSIGNED_8_BIT_INTEGER, attributeIdentifier=0, direction=0, minimumReportingInterval=1, maximumReportingInterval=900, reportableChange=1]]]]
14:39:34.317 [DEBUG] [ing.zigbee.handler.ZigBeeThingHandler] - 7C2524000016B44E: Channel initialisation complete
14:39:34.327 [DEBUG] [ing.zigbee.handler.ZigBeeThingHandler] - 7C2524000016B44E: Setting ONLINE/OFFLINE timeout interval to: 1830
14:39:34.329 [DEBUG] [g.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker added for thingUID=zigbee:device:husbzb_zigbee:7c2524000016b44e
14:39:34.332 [DEBUG] [g.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker reset for handler with thingUID=zigbee:device:husbzb_zigbee:7c2524000016b44e
14:39:34.334 [DEBUG] [g.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker scheduled task for thingUID=zigbee:device:husbzb_zigbee:7c2524000016b44e in 1830 seconds
14:39:34.321 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspMessageSentHandler [networkId=0, type=EMBER_OUTGOING_DIRECT, indexOrDestination=4DD1, apsFrame=EmberApsFrame [profileId=0104, clusterId=0000, sourceEndpoint=1, destinationEndpoint=1, options=[EMBER_APS_OPTION_RETRY, EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=ED], messageTag=06, status=EMBER_SUCCESS, messageContents=]
14:39:34.319 [DEBUG] [ee.transaction.ZigBeeTransactionQueue] - 7C2524000016B44E: Added transaction to queue, len=2, transaction=ZigBeeTransaction [ieeeAddress=7C2524000016B44E queueTime=0, state=WAITING, sendCnt=0, command=DefaultResponse [Level Control: 0000/0 -> 6DF4/1, cluster=0008, TID=6E, commandIdentifier=7, statusCode=SUCCESS]]
14:39:34.339 [DEBUG] [.transaction.ZigBeeTransactionManager] - Transaction Manager: Send Next transaction. outstandingTransactions=3, outstandingQueues=4, sleepy=1/3
14:39:34.330 [DEBUG] [.transaction.ZigBeeTransactionManager] - transactionListenerRemoved: 3 outstanding
14:39:34.342 [DEBUG] [ee.transaction.ZigBeeTransactionQueue] - 7C2524000016B44E: transactionComplete, state=COMPLETE, outstanding=0
14:39:34.344 [DEBUG] [.transaction.ZigBeeTransactionManager] - Transaction Manager: Send Next transaction. outstandingTransactions=3, outstandingQueues=4, sleepy=1/3
14:39:34.346 [DEBUG] [.transaction.ZigBeeTransactionManager] - 6DF4/1: Sending ZigBeeTransaction [ieeeAddress=7C2524000016B44E queueTime=395, state=WAITING, sendCnt=0, command=DefaultResponse [On/Off: 0000/0 -> 6DF4/1, cluster=0006, TID=6D, commandIdentifier=7, statusCode=SUCCESS]]
14:39:34.348 [DEBUG] [.transaction.ZigBeeTransactionManager] - transactionListenerAdded: 4 outstanding
14:39:34.350 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX CMD: DefaultResponse [On/Off: 0000/0 -> 6DF4/1, cluster=0006, TID=6D, commandIdentifier=7, statusCode=SUCCESS]
14:39:34.352 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=CLIENT_TO_SERVER, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=109, commandId=11]
14:39:34.350 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX STA: msgTag=06 state=RX_ACK
14:39:34.354 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0000/1, destinationAddress=6DF4/1, profile=0104, cluster=0006, addressMode=DEVICE, radius=8, apsSecurity=false, ackRequest=true, apsCounter=A9, rssi=--, lqi=--, payload=10 6D 0B 07 00]
14:39:34.358 [DEBUG] [.transaction.ZigBeeTransactionManager] - notifyTransactionProgress: TID=06, state=RX_ACK, outstanding=4
14:39:34.362 [DEBUG] [ee.transaction.ZigBeeTransactionQueue] - 7C2524000016B44E: Added transaction to queue, len=2, transaction=ZigBeeTransaction [ieeeAddress=7C2524000016B44E queueTime=0, state=WAITING, sendCnt=0, command=ManagementBindRequest [0000/0 -> 6DF4/0, cluster=0033, TID=--, startIndex=0]]
14:39:34.366 [DEBUG] [.transaction.ZigBeeTransactionManager] - Transaction complete: ZigBeeTransaction [ieeeAddress=0022A30000134B82 queueTime=5703, state=COMPLETE, sendCnt=1, command=DefaultResponse [Basic: 0000/0 -> 4DD1/1, cluster=0000, TID=06, commandIdentifier=1, statusCode=SUCCESS]]
14:39:34.367 [DEBUG] [.transaction.ZigBeeTransactionManager] - Transaction Manager: Send Next transaction. outstandingTransactions=4, outstandingQueues=4, sleepy=1/3
14:39:34.368 [DEBUG] [.transaction.ZigBeeTransactionManager] - transactionListenerRemoved: 3 outstanding
14:39:34.373 [DEBUG] [ee.transaction.ZigBeeTransactionQueue] - 0022A30000134B82: transactionComplete, state=COMPLETE, outstanding=0
14:39:34.375 [DEBUG] [.transaction.ZigBeeTransactionManager] - Transaction Manager: Send Next transaction. outstandingTransactions=3, outstandingQueues=4, sleepy=0/3
14:39:34.377 [DEBUG] [.transaction.ZigBeeTransactionManager] - 4DD1/1: Sending ZigBeeTransaction [ieeeAddress=0022A30000134B82 queueTime=4791, state=WAITING, sendCnt=0, command=ReadAttributesCommand [Basic: 0000/0 -> 4DD1/1, cluster=0000, TID=70, identifiers=[5, 4, 3, 2, 6, 0, 1]]]
14:39:34.378 [DEBUG] [.transaction.ZigBeeTransactionManager] - transactionListenerAdded: 4 outstanding
14:39:34.381 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspSendUnicastResponse [networkId=0, status=EMBER_SUCCESS, sequence=F7]
14:39:34.385 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ReadAttributesCommand [Basic: 0000/0 -> 4DD1/1, cluster=0000, TID=70, identifiers=[5, 4, 3, 2, 6, 0, 1]]
14:39:34.387 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=CLIENT_TO_SERVER, disableDefaultResponse=false, manufacturerCode=0, sequenceNumber=112, commandId=0]
14:39:34.389 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0000/1, destinationAddress=4DD1/1, profile=0104, cluster=0000, addressMode=DEVICE, radius=8, apsSecurity=false, ackRequest=true, apsCounter=AA, rssi=--, lqi=--, payload=00 70 00 05 00 04 00 03 00 02 00 06 00 00 00 01 00]
14:39:34.391 [DEBUG] [.zigbee.transaction.ZigBeeTransaction] - Transaction state changed: nwk=4DD1, TID=06, event=RX_ACK, state=COMPLETE
14:39:34.406 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspSendUnicastResponse [networkId=0, status=EMBER_SUCCESS, sequence=F8]
14:39:34.427 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspMessageSentHandler [networkId=0, type=EMBER_OUTGOING_DIRECT, indexOrDestination=6DF4, apsFrame=EmberApsFrame [profileId=0104, clusterId=0006, sourceEndpoint=1, destinationEndpoint=1, options=[EMBER_APS_OPTION_RETRY, EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=F7], messageTag=6D, status=EMBER_SUCCESS, messageContents=]
14:39:34.429 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX STA: msgTag=6D state=RX_ACK
14:39:34.434 [DEBUG] [.transaction.ZigBeeTransactionManager] - notifyTransactionProgress: TID=6D, state=RX_ACK, outstanding=4
14:39:34.440 [DEBUG] [.transaction.ZigBeeTransactionManager] - Transaction complete: ZigBeeTransaction [ieeeAddress=7C2524000016B44E queueTime=489, state=COMPLETE, sendCnt=1, command=DefaultResponse [On/Off: 0000/0 -> 6DF4/1, cluster=0006, TID=6D, commandIdentifier=7, statusCode=SUCCESS]]
14:39:34.442 [DEBUG] [.transaction.ZigBeeTransactionManager] - transactionListenerRemoved: 3 outstanding
14:39:34.444 [DEBUG] [ee.transaction.ZigBeeTransactionQueue] - 7C2524000016B44E: transactionComplete, state=COMPLETE, outstanding=0
14:39:34.445 [DEBUG] [.transaction.ZigBeeTransactionManager] - Transaction Manager: Send Next transaction. outstandingTransactions=3, outstandingQueues=4, sleepy=1/3
14:39:34.448 [DEBUG] [.transaction.ZigBeeTransactionManager] - 6DF4/1: Sending ZigBeeTransaction [ieeeAddress=7C2524000016B44E queueTime=130, state=WAITING, sendCnt=0, command=DefaultResponse [Level Control: 0000/0 -> 6DF4/1, cluster=0008, TID=6E, commandIdentifier=7, statusCode=SUCCESS]]
14:39:34.450 [DEBUG] [.transaction.ZigBeeTransactionManager] - transactionListenerAdded: 4 outstanding
14:39:34.452 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX CMD: DefaultResponse [Level Control: 0000/0 -> 6DF4/1, cluster=0008, TID=6E, commandIdentifier=7, statusCode=SUCCESS]
14:39:34.454 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=CLIENT_TO_SERVER, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=110, commandId=11]
14:39:34.456 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0000/1, destinationAddress=6DF4/1, profile=0104, cluster=0008, addressMode=DEVICE, radius=8, apsSecurity=false, ackRequest=true, apsCounter=AB, rssi=--, lqi=--, payload=10 6E 0B 07 00]
14:39:34.459 [DEBUG] [.zigbee.transaction.ZigBeeTransaction] - Transaction state changed: nwk=6DF4, TID=6D, event=RX_ACK, state=COMPLETE
14:39:34.470 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspSendUnicastResponse [networkId=0, status=EMBER_SUCCESS, sequence=F9]
14:39:34.500 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspMessageSentHandler [networkId=0, type=EMBER_OUTGOING_DIRECT, indexOrDestination=6DF4, apsFrame=EmberApsFrame [profileId=0104, clusterId=0008, sourceEndpoint=1, destinationEndpoint=1, options=[EMBER_APS_OPTION_RETRY, EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=F9], messageTag=6E, status=EMBER_SUCCESS, messageContents=]
14:39:34.503 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX STA: msgTag=6E state=RX_ACK
14:39:34.505 [DEBUG] [.transaction.ZigBeeTransactionManager] - notifyTransactionProgress: TID=6E, state=RX_ACK, outstanding=4
14:39:34.507 [DEBUG] [.transaction.ZigBeeTransactionManager] - Transaction complete: ZigBeeTransaction [ieeeAddress=7C2524000016B44E queueTime=189, state=COMPLETE, sendCnt=1, command=DefaultResponse [Level Control: 0000/0 -> 6DF4/1, cluster=0008, TID=6E, commandIdentifier=7, statusCode=SUCCESS]]
14:39:34.512 [DEBUG] [.transaction.ZigBeeTransactionManager] - transactionListenerRemoved: 3 outstanding
14:39:34.514 [DEBUG] [ee.transaction.ZigBeeTransactionQueue] - 7C2524000016B44E: transactionComplete, state=COMPLETE, outstanding=0
14:39:34.516 [DEBUG] [.transaction.ZigBeeTransactionManager] - Transaction Manager: Send Next transaction. outstandingTransactions=3, outstandingQueues=4, sleepy=1/3
14:39:34.519 [DEBUG] [.transaction.ZigBeeTransactionManager] - 6DF4/0: Sending ZigBeeTransaction [ieeeAddress=7C2524000016B44E queueTime=156, state=WAITING, sendCnt=0, command=ManagementBindRequest [0000/0 -> 6DF4/0, cluster=0033, TID=71, startIndex=0]]
14:39:34.524 [DEBUG] [.transaction.ZigBeeTransactionManager] - transactionListenerAdded: 4 outstanding
14:39:34.530 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ManagementBindRequest [0000/0 -> 6DF4/0, cluster=0033, TID=71, startIndex=0]
14:39:34.533 [DEBUG] [database.ZigBeeNetworkDatabaseManager] - 7C2524000015C6BB: Data store: Writing node.
14:39:34.535 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0000/0, destinationAddress=6DF4/0, profile=0000, cluster=0033, addressMode=DEVICE, radius=8, apsSecurity=false, ackRequest=true, apsCounter=AC, rssi=--, lqi=--, payload=71 00]
14:39:34.538 [DEBUG] [.zigbee.transaction.ZigBeeTransaction] - Transaction state changed: nwk=6DF4, TID=6E, event=RX_ACK, state=COMPLETE
14:39:34.551 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspSendUnicastResponse [networkId=0, status=EMBER_SUCCESS, sequence=FA]
14:39:34.573 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingMessageHandler [networkId=0, type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=0104, clusterId=0008, sourceEndpoint=1, destinationEndpoint=1, options=[], groupId=0, sequence=8A], lastHopLqi=255, lastHopRssi=-58, sender=2BC2, bindingIndex=255, addressIndex=255, messageContents=18 C8 0A 00 00 20 FE]
14:39:34.575 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=2BC2/1, destinationAddress=0000/1, profile=0104, cluster=0008, addressMode=DEVICE, radius=0, apsSecurity=false, ackRequest=false, apsCounter=8A, rssi=-58, lqi=FF, payload=18 C8 0A 00 00 20 FE]
14:39:34.577 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=200, commandId=10]
14:39:34.579 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ReportAttributesCommand [Level Control: 2BC2/1 -> 0000/1, cluster=0008, TID=C8, reports=[AttributeReport [attributeDataType=UNSIGNED_8_BIT_INTEGER, attributeIdentifier=0, attributeValue=254]]]
14:39:34.581 [DEBUG] [.transaction.ZigBeeTransactionManager] - notifyTransactionCommand: ReportAttributesCommand [Level Control: 2BC2/1 -> 0000/1, cluster=0008, TID=C8, reports=[AttributeReport [attributeDataType=UNSIGNED_8_BIT_INTEGER, attributeIdentifier=0, attributeValue=254]]]
14:39:34.610 [DEBUG] [.converter.ZigBeeConverterSwitchLevel] - 7C2524000015C6BB: ZigBee attribute reports ZclAttribute [cluster=Level Control, id=0, name=Current Level, dataType=UNSIGNED_8_BIT_INTEGER, lastValue=254, lastReportTime=Thu Apr 15 14:39:34 MST 2021, implemented=true]
14:39:34.615 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspMessageSentHandler [networkId=0, type=EMBER_OUTGOING_DIRECT, indexOrDestination=6DF4, apsFrame=EmberApsFrame [profileId=0000, clusterId=0033, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_RETRY, EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=FA], messageTag=71, status=EMBER_SUCCESS, messageContents=]
14:39:34.618 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX STA: msgTag=71 state=RX_ACK
14:39:34.628 [DEBUG] [.transaction.ZigBeeTransactionManager] - notifyTransactionProgress: TID=71, state=RX_ACK, outstanding=4
14:39:34.636 [DEBUG] [.zigbee.transaction.ZigBeeTransaction] - Transaction state changed: nwk=6DF4, TID=71, event=RX_ACK, state=ACKED
14:39:34.644 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingMessageHandler [networkId=0, type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=0000, clusterId=8033, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_RETRY], groupId=0, sequence=CA], lastHopLqi=253, lastHopRssi=-54, sender=6DF4, bindingIndex=255, addressIndex=255, messageContents=71 00 02 00 02 4E B4 16 00 00 24 25 7C 01 08 00 03 CF 35 3D 0B 00 6F 0D 00 01 4E B4 16 00 00 24 25 7C 01 06 00 03 CF 35 3D 0B 00 6F 0D 00 01]
14:39:34.649 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=6DF4/0, destinationAddress=0000/0, profile=0000, cluster=8033, addressMode=DEVICE, radius=0, apsSecurity=false, ackRequest=false, apsCounter=CA, rssi=-54, lqi=FD, payload=71 00 02 00 02 4E B4 16 00 00 24 25 7C 01 08 00 03 CF 35 3D 0B 00 6F 0D 00 01 4E B4 16 00 00 24 25 7C 01 06 00 03 CF 35 3D 0B 00 6F 0D 00 01]
14:39:34.655 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ManagementBindResponse [6DF4/0 -> 0000/0, cluster=8033, TID=71, status=SUCCESS, bindingTableEntries=2, startIndex=0, bindingTableList=[BindingTable [srcAddr=7C2524000016B44E/1, dstAddr=000D6F000B3D35CF/1, clusterId=0008], BindingTable [srcAddr=7C2524000016B44E/1, dstAddr=000D6F000B3D35CF/1, clusterId=0006]]]
14:39:34.659 [DEBUG] [.transaction.ZigBeeTransactionManager] - notifyTransactionCommand: ManagementBindResponse [6DF4/0 -> 0000/0, cluster=8033, TID=71, status=SUCCESS, bindingTableEntries=2, startIndex=0, bindingTableList=[BindingTable [srcAddr=7C2524000016B44E/1, dstAddr=000D6F000B3D35CF/1, clusterId=0008], BindingTable [srcAddr=7C2524000016B44E/1, dstAddr=000D6F000B3D35CF/1, clusterId=0006]]]
14:39:34.663 [DEBUG] [.transaction.ZigBeeTransactionManager] - Transaction complete: ZigBeeTransaction [ieeeAddress=7C2524000016B44E queueTime=300, state=COMPLETE, sendCnt=1, command=ManagementBindRequest [0000/0 -> 6DF4/0, cluster=0033, TID=71, startIndex=0]]
14:39:34.666 [DEBUG] [com.zsmartsystems.zigbee.ZigBeeNode ] - 7C2524000016B44E: Binding table updated: [BindingTable [srcAddr=7C2524000016B44E/1, dstAddr=000D6F000B3D35CF/1, clusterId=0008], BindingTable [srcAddr=7C2524000016B44E/1, dstAddr=000D6F000B3D35CF/1, clusterId=0006]]
14:39:34.670 [DEBUG] [ing.zigbee.handler.ZigBeeThingHandler] - 7C2524000016B44E: Polling initialised at 937042ms
14:39:34.674 [DEBUG] [ing.zigbee.handler.ZigBeeThingHandler] - 7C2524000016B44E: Done initialising ZigBee Thing handler
14:39:34.678 [DEBUG] [database.ZigBeeNetworkDatabaseManager] - 7C2524000016B44E: Data store: Deferring write for 250ms.
14:39:34.681 [DEBUG] [.transaction.ZigBeeTransactionManager] - transactionListenerRemoved: 3 outstanding
14:39:34.683 [DEBUG] [ee.transaction.ZigBeeTransactionQueue] - 7C2524000016B44E: transactionComplete, state=COMPLETE, outstanding=0
14:39:34.685 [DEBUG] [g.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker reset for handler with thingUID=zigbee:device:husbzb_zigbee:7c2524000016b44e
14:39:34.684 [INFO ] [hab.event.ThingStatusInfoChangedEvent] - Thing 'zigbee:device:husbzb_zigbee:7c2524000016b44e' changed from UNKNOWN to ONLINE
14:39:34.687 [DEBUG] [g.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker cancelled task for thingUID=zigbee:device:husbzb_zigbee:7c2524000016b44e
14:39:34.690 [INFO ] [openhab.event.FirmwareStatusInfoEvent] - Firmware status of thing zigbee:device:husbzb_zigbee:7c2524000016b44e changed to UNKNOWN.
14:39:34.686 [DEBUG] [.transaction.ZigBeeTransactionManager] - Transaction Manager: Send Next transaction. outstandingTransactions=3, outstandingQueues=3, sleepy=1/3
14:39:34.691 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingMessageHandler [networkId=0, type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=0104, clusterId=0006, sourceEndpoint=1, destinationEndpoint=1, options=[], groupId=0, sequence=CB], lastHopLqi=254, lastHopRssi=-54, sender=6DF4, bindingIndex=255, addressIndex=255, messageContents=18 88 0A 00 00 10 01]
14:39:34.698 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=6DF4/1, destinationAddress=0000/1, profile=0104, cluster=0006, addressMode=DEVICE, radius=0, apsSecurity=false, ackRequest=false, apsCounter=CB, rssi=-54, lqi=FE, payload=18 88 0A 00 00 10 01]
14:39:34.691 [DEBUG] [g.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker scheduled task for thingUID=zigbee:device:husbzb_zigbee:7c2524000016b44e in 1830 seconds
14:39:34.700 [DEBUG] [nding.zigbee.internal.ZigBeeDataStore] - 7C2524000015C6BB: ZigBee saving network state complete.
14:39:34.708 [DEBUG] [.converter.ZigBeeConverterSwitchLevel] - 7C2524000016B44E: ZigBee attribute reports ZclAttribute [cluster=Level Control, id=0, name=Current Level, dataType=UNSIGNED_8_BIT_INTEGER, lastValue=254, lastReportTime=Thu Apr 15 14:39:33 MST 2021, implemented=true]
14:39:34.711 [DEBUG] [.converter.ZigBeeBaseChannelConverter] - 7C2524000016B44E: Channel zigbee:device:husbzb_zigbee:7c2524000016b44e:7C2524000016B44E_1_dimmer updated to 100
14:39:34.714 [DEBUG] [ing.zigbee.handler.ZigBeeThingHandler] - 7C2524000016B44E: Updating ZigBee channel state zigbee:device:husbzb_zigbee:7c2524000016b44e:7C2524000016B44E_1_dimmer to 100
14:39:34.718 [DEBUG] [g.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker reset for handler with thingUID=zigbee:device:husbzb_zigbee:7c2524000016b44e
14:39:34.722 [DEBUG] [g.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker cancelled task for thingUID=zigbee:device:husbzb_zigbee:7c2524000016b44e
14:39:34.724 [DEBUG] [g.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker scheduled task for thingUID=zigbee:device:husbzb_zigbee:7c2524000016b44e in 1830 seconds
14:39:34.707 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=136, commandId=10]
14:39:34.727 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ReportAttributesCommand [On/Off: 6DF4/1 -> 0000/1, cluster=0006, TID=88, reports=[AttributeReport [attributeDataType=BOOLEAN, attributeIdentifier=0, attributeValue=true]]]
14:39:34.730 [DEBUG] [.transaction.ZigBeeTransactionManager] - notifyTransactionCommand: ReportAttributesCommand [On/Off: 6DF4/1 -> 0000/1, cluster=0006, TID=88, reports=[AttributeReport [attributeDataType=BOOLEAN, attributeIdentifier=0, attributeValue=true]]]
14:39:34.733 [DEBUG] [.converter.ZigBeeConverterSwitchLevel] - 7C2524000016B44E: ZigBee attribute reports ZclAttribute [cluster=On/Off, id=0, name=On Off, dataType=BOOLEAN, lastValue=true, lastReportTime=Thu Apr 15 14:39:34 MST 2021, implemented=false]
14:39:34.735 [DEBUG] [.converter.ZigBeeBaseChannelConverter] - 7C2524000016B44E: Channel zigbee:device:husbzb_zigbee:7c2524000016b44e:7C2524000016B44E_1_dimmer updated to 100
14:39:34.737 [DEBUG] [ing.zigbee.handler.ZigBeeThingHandler] - 7C2524000016B44E: Updating ZigBee channel state zigbee:device:husbzb_zigbee:7c2524000016b44e:7C2524000016B44E_1_dimmer to 100
14:39:34.738 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingMessageHandler [networkId=0, type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=0104, clusterId=0008, sourceEndpoint=1, destinationEndpoint=1, options=[], groupId=0, sequence=CC], lastHopLqi=242, lastHopRssi=-54, sender=6DF4, bindingIndex=255, addressIndex=255, messageContents=18 89 0A 00 00 20 FE]
14:39:34.744 [DEBUG] [g.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker reset for handler with thingUID=zigbee:device:husbzb_zigbee:7c2524000016b44e
14:39:34.745 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=6DF4/1, destinationAddress=0000/1, profile=0104, cluster=0008, addressMode=DEVICE, radius=0, apsSecurity=false, ackRequest=false, apsCounter=CC, rssi=-54, lqi=F2, payload=18 89 0A 00 00 20 FE]
14:39:34.746 [DEBUG] [g.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker cancelled task for thingUID=zigbee:device:husbzb_zigbee:7c2524000016b44e
14:39:34.748 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=137, commandId=10]
14:39:34.749 [DEBUG] [g.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker scheduled task for thingUID=zigbee:device:husbzb_zigbee:7c2524000016b44e in 1830 seconds
14:39:34.751 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ReportAttributesCommand [Level Control: 6DF4/1 -> 0000/1, cluster=0008, TID=89, reports=[AttributeReport [attributeDataType=UNSIGNED_8_BIT_INTEGER, attributeIdentifier=0, attributeValue=254]]]
14:39:34.755 [DEBUG] [.transaction.ZigBeeTransactionManager] - notifyTransactionCommand: ReportAttributesCommand [Level Control: 6DF4/1 -> 0000/1, cluster=0008, TID=89, reports=[AttributeReport [attributeDataType=UNSIGNED_8_BIT_INTEGER, attributeIdentifier=0, attributeValue=254]]]
14:39:34.758 [DEBUG] [.converter.ZigBeeConverterSwitchLevel] - 7C2524000016B44E: ZigBee attribute reports ZclAttribute [cluster=Level Control, id=0, name=Current Level, dataType=UNSIGNED_8_BIT_INTEGER, lastValue=254, lastReportTime=Thu Apr 15 14:39:34 MST 2021, implemented=true]
14:39:34.760 [DEBUG] [.converter.ZigBeeBaseChannelConverter] - 7C2524000016B44E: Channel zigbee:device:husbzb_zigbee:7c2524000016b44e:7C2524000016B44E_1_dimmer updated to 100
14:39:34.771 [DEBUG] [ing.zigbee.handler.ZigBeeThingHandler] - 7C2524000016B44E: Updating ZigBee channel state zigbee:device:husbzb_zigbee:7c2524000016b44e:7C2524000016B44E_1_dimmer to 100
14:39:34.779 [DEBUG] [g.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker reset for handler with thingUID=zigbee:device:husbzb_zigbee:7c2524000016b44e
14:39:34.781 [DEBUG] [g.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker cancelled task for thingUID=zigbee:device:husbzb_zigbee:7c2524000016b44e
14:39:34.783 [DEBUG] [g.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker scheduled task for thingUID=zigbee:device:husbzb_zigbee:7c2524000016b44e in 1830 seconds
14:39:34.828 [INFO ] [ave.internal.protocol.ZWaveController] - Starting ZWave controller
14:39:34.830 [INFO ] [ave.internal.protocol.ZWaveController] - ZWave timeout is set to 5000ms. Soft reset is false.
14:39:34.937 [DEBUG] [database.ZigBeeNetworkDatabaseManager] - 7C2524000016B44E: Data store: Writing node.
14:39:34.976 [DEBUG] [nding.zigbee.internal.ZigBeeDataStore] - 7C2524000016B44E: ZigBee saving network state complete.
14:39:35.346 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingMessageHandler [networkId=0, type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=0104, clusterId=0000, sourceEndpoint=1, destinationEndpoint=1, options=[EMBER_APS_OPTION_RETRY], groupId=0, sequence=78], lastHopLqi=226, lastHopRssi=-56, sender=4DD1, bindingIndex=255, addressIndex=255, messageContents=08 70 01 05 00 00 42 19 6C 65 61 6B 53 4D 41 52 54 20 57 61 74 65 72 20 53 65 6E 73 6F 72 20 56 32 04 00 00 42 06 57 41 58 4D 41 4E 03 00 00 20 03 02 00 00 20 01 06 00 00 42 08 32 30 31 36 30 32 31 36 00 00 00 20 01 01 00 00 20 34]
14:39:35.363 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=4DD1/1, destinationAddress=0000/1, profile=0104, cluster=0000, addressMode=DEVICE, radius=0, apsSecurity=false, ackRequest=false, apsCounter=78, rssi=-56, lqi=E2, payload=08 70 01 05 00 00 42 19 6C 65 61 6B 53 4D 41 52 54 20 57 61 74 65 72 20 53 65 6E 73 6F 72 20 56 32 04 00 00 42 06 57 41 58 4D 41 4E 03 00 00 20 03 02 00 00 20 01 06 00 00 42 08 32 30 31 36 30 32 31 36 00 00 00 20 01 01 00 00 20 34]
14:39:35.365 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=false, manufacturerCode=0, sequenceNumber=112, commandId=1]
14:39:35.368 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ReadAttributesResponse [Basic: 4DD1/1 -> 0000/1, cluster=0000, TID=70, records=[ReadAttributeStatusRecord [status=SUCCESS, attributeIdentifier=5, attributeDataType=CHARACTER_STRING, attributeValue=leakSMART Water Sensor V2], ReadAttributeStatusRecord [status=SUCCESS, attributeIdentifier=4, attributeDataType=CHARACTER_STRING, attributeValue=WAXMAN], ReadAttributeStatusRecord [status=SUCCESS, attributeIdentifier=3, attributeDataType=UNSIGNED_8_BIT_INTEGER, attributeValue=3], ReadAttributeStatusRecord [status=SUCCESS, attributeIdentifier=2, attributeDataType=UNSIGNED_8_BIT_INTEGER, attributeValue=1], ReadAttributeStatusRecord [status=SUCCESS, attributeIdentifier=6, attributeDataType=CHARACTER_STRING, attributeValue=20160216], ReadAttributeStatusRecord [status=SUCCESS, attributeIdentifier=0, attributeDataType=UNSIGNED_8_BIT_INTEGER, attributeValue=1], ReadAttributeStatusRecord [status=SUCCESS, attributeIdentifier=1, attributeDataType=UNSIGNED_8_BIT_INTEGER, attributeValue=52]]]
14:39:35.375 [DEBUG] [.transaction.ZigBeeTransactionManager] - notifyTransactionCommand: ReadAttributesResponse [Basic: 4DD1/1 -> 0000/1, cluster=0000, TID=70, records=[ReadAttributeStatusRecord [status=SUCCESS, attributeIdentifier=5, attributeDataType=CHARACTER_STRING, attributeValue=leakSMART Water Sensor V2], ReadAttributeStatusRecord [status=SUCCESS, attributeIdentifier=4, attributeDataType=CHARACTER_STRING, attributeValue=WAXMAN], ReadAttributeStatusRecord [status=SUCCESS, attributeIdentifier=3, attributeDataType=UNSIGNED_8_BIT_INTEGER, attributeValue=3], ReadAttributeStatusRecord [status=SUCCESS, attributeIdentifier=2, attributeDataType=UNSIGNED_8_BIT_INTEGER, attributeValue=1], ReadAttributeStatusRecord [status=SUCCESS, attributeIdentifier=6, attributeDataType=CHARACTER_STRING, attributeValue=20160216], ReadAttributeStatusRecord [status=SUCCESS, attributeIdentifier=0, attributeDataType=UNSIGNED_8_BIT_INTEGER, attributeValue=1], ReadAttributeStatusRecord [status=SUCCESS, attributeIdentifier=1, attributeDataType=UNSIGNED_8_BIT_INTEGER, attributeValue=52]]]
14:39:35.382 [DEBUG] [.transaction.ZigBeeTransactionManager] - Transaction complete: ZigBeeTransaction [ieeeAddress=0022A30000134B82 queueTime=5797, state=COMPLETE, sendCnt=1, command=ReadAttributesCommand [Basic: 0000/0 -> 4DD1/1, cluster=0000, TID=70, identifiers=[5, 4, 3, 2, 6, 0, 1]]]
14:39:35.384 [DEBUG] [.transaction.ZigBeeTransactionManager] - transactionListenerRemoved: 2 outstanding
14:39:35.388 [DEBUG] [iscovery.ZigBeeNodePropertyDiscoverer] - 0022A30000134B82: ZigBee node property discovery using OTA cluster on endpoint 4DD1/1
14:39:35.388 [DEBUG] [ee.transaction.ZigBeeTransactionQueue] - 0022A30000134B82: Added transaction to queue, len=4, transaction=ZigBeeTransaction [ieeeAddress=0022A30000134B82 queueTime=0, state=WAITING, sendCnt=0, command=DefaultResponse [Basic: 0000/0 -> 4DD1/1, cluster=0000, TID=70, commandIdentifier=1, statusCode=SUCCESS]]
14:39:35.399 [DEBUG] [.transaction.ZigBeeTransactionManager] - Transaction Manager: Send Next transaction. outstandingTransactions=2, outstandingQueues=3, sleepy=1/3
14:39:35.397 [DEBUG] [m.zsmartsystems.zigbee.zcl.ZclCluster] - readSync request: 2
14:39:35.404 [DEBUG] [ee.transaction.ZigBeeTransactionQueue] - 0022A30000134B82: Added transaction to queue, len=5, transaction=ZigBeeTransaction [ieeeAddress=0022A30000134B82 queueTime=0, state=WAITING, sendCnt=0, command=ReadAttributesCommand [Ota Upgrade: 0000/0 -> 4DD1/1, cluster=0019, TID=--, identifiers=[2]]]