23:09:15.482 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=4, ackNum=1, reTx=false, data=95 00 01 18 00]
23:09:15.534 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=1, ackNum=5, reTx=false, data=95 80 01 18 00 02]
23:09:15.535 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=4, ackNum=1, reTx=false, data=95 00 01 18 00]
23:09:15.535 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
23:09:15.536 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=2, notRdy=false]
23:09:15.537 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
23:09:16.145 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=2, ackNum=5, reTx=false, data=95 90 01 3F 00 03 FD FF 04 01 06 00 01 FF 00 01 00 00 03 00 00 00]
23:09:16.146 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
23:09:16.147 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 0
23:09:16.147 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspMessageSentHandler [networkId=0, type=EMBER_OUTGOING_MULTICAST, indexOrDestination=FFFD, apsFrame=EmberApsFrame [profileId=0104, clusterId=0006, sourceEndpoint=1, destinationEndpoint=255, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=03], messageTag=00, status=EMBER_SUCCESS, messageContents=]
23:09:16.148 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX STA: msgTag=00 state=RX_ACK
23:09:16.148 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=3, notRdy=false]
23:09:16.149 [DEBUG] [.transaction.ZigBeeTransactionManager] - notifyTransactionProgress: TID=00, state=RX_ACK, outstanding=0
23:09:16.483 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue size: 1
23:09:16.484 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=5, ackNum=3, reTx=false, data=96 00 01 18 00]
23:09:16.510 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=3, ackNum=6, reTx=false, data=96 80 01 18 00 02]
23:09:16.512 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=5, ackNum=3, reTx=false, data=96 00 01 18 00]
23:09:16.512 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
23:09:16.512 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
23:09:16.514 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=4, notRdy=false]
23:09:17.485 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue size: 1
23:09:17.486 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=6, ackNum=4, reTx=false, data=97 00 01 18 00]
23:09:17.513 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=4, ackNum=7, reTx=false, data=97 80 01 18 00 02]
23:09:17.514 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=6, ackNum=4, reTx=false, data=97 00 01 18 00]
23:09:17.514 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
23:09:17.514 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
23:09:17.515 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=5, notRdy=false]
23:09:17.894 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=5, ackNum=7, reTx=false, data=97 90 01 3F 00 03 FD FF 04 01 06 00 01 FF 00 01 00 00 04 00 00 00]
23:09:17.895 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
23:09:17.896 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
23:09:17.896 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=6, notRdy=false]
23:09:17.896 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspMessageSentHandler [networkId=0, type=EMBER_OUTGOING_MULTICAST, indexOrDestination=FFFD, apsFrame=EmberApsFrame [profileId=0104, clusterId=0006, sourceEndpoint=1, destinationEndpoint=255, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=04], messageTag=00, status=EMBER_SUCCESS, messageContents=]
23:09:17.897 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX STA: msgTag=00 state=RX_ACK
23:09:17.898 [DEBUG] [.transaction.ZigBeeTransactionManager] - notifyTransactionProgress: TID=00, state=RX_ACK, outstanding=0
23:09:18.487 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue size: 1
23:09:18.488 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=7, ackNum=6, reTx=false, data=98 00 01 18 00]
23:09:18.514 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=6, ackNum=0, reTx=false, data=98 80 01 18 00 02]
23:09:18.515 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=7, ackNum=6, reTx=false, data=98 00 01 18 00]
23:09:18.515 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
23:09:18.515 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
23:09:18.516 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=7, notRdy=false]
23:09:19.489 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue size: 1
23:09:19.490 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=0, ackNum=7, reTx=false, data=99 00 01 18 00]
23:09:19.517 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=7, ackNum=1, reTx=false, data=99 80 01 18 00 02]
23:09:19.517 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=0, ackNum=7, reTx=false, data=99 00 01 18 00]
23:09:19.518 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
23:09:19.518 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
23:09:19.519 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=0, notRdy=false]
23:09:19.894 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=0, ackNum=1, reTx=false, data=99 90 01 3F 00 03 FD FF 04 01 06 00 01 FF 00 01 00 00 05 00 00 00]
23:09:19.895 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
23:09:19.895 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
23:09:19.896 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=1, notRdy=false]
23:09:19.896 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspMessageSentHandler [networkId=0, type=EMBER_OUTGOING_MULTICAST, indexOrDestination=FFFD, apsFrame=EmberApsFrame [profileId=0104, clusterId=0006, sourceEndpoint=1, destinationEndpoint=255, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=05], messageTag=00, status=EMBER_SUCCESS, messageContents=]
23:09:19.897 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX STA: msgTag=00 state=RX_ACK
23:09:19.898 [DEBUG] [.transaction.ZigBeeTransactionManager] - notifyTransactionProgress: TID=00, state=RX_ACK, outstanding=0
23:09:20.491 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue size: 1
23:09:20.492 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=1, ackNum=1, reTx=false, data=9A 00 01 18 00]
23:09:20.518 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=1, ackNum=2, reTx=false, data=9A 80 01 18 00 02]
23:09:20.519 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=1, ackNum=1, reTx=false, data=9A 00 01 18 00]
23:09:20.520 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
23:09:20.520 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
23:09:20.521 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=2, notRdy=false]
23:09:20.911 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=2, ackNum=2, reTx=false, data=9A 90 01 59 00 76 77 DF 5C 61 FE FF AC 33 BC C8 CE 01 00 00]
23:09:20.912 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
23:09:20.912 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
23:09:20.912 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=3, notRdy=false]
23:09:20.913 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingRouteRecordHandler [networkId=0, source=7776, sourceEui=BC33ACFFFE615CDF, lastHopLqi=200, lastHopRssi=-50, relayList=0000]
23:09:20.958 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=3, ackNum=2, reTx=false, data=9A 90 01 45 00 00 04 01 06 00 01 01 00 01 00 00 06 C8 CE 76 77 FF FF 04 01 41 FD 00 04]
23:09:20.958 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
23:09:20.958 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
23:09:20.959 [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=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=06], lastHopLqi=200, lastHopRssi=-50, sender=7776, bindingIndex=255, addressIndex=255, messageContents=01 41 FD 00]
23:09:20.959 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=4, notRdy=false]
23:09:20.960 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=7776/1, destinationAddress=0000/1, profile=0104, cluster=0006, addressMode=DEVICE, radius=0, apsSecurity=false, ackRequest=false, apsCounter=06, rssi=-50, lqi=C8, payload=01 41 FD 00]
23:09:20.961 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFE615CDF: Node update. NWK Address=7776
23:09:20.962 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFE615CDF: Node 7776 is not updated
23:09:20.963 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=CLUSTER_SPECIFIC_COMMAND, manufacturerSpecific=false, direction=CLIENT_TO_SERVER, disableDefaultResponse=false, manufacturerCode=0, sequenceNumber=65, commandId=253]
23:09:20.964 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFE615CDF: Unknown command 253
23:09:20.965 [DEBUG] [ee.transaction.ZigBeeTransactionQueue] - BC33ACFFFE615CDF: Added transaction to queue, len=1, transaction=ZigBeeTransaction [ieeeAddress=BC33ACFFFE615CDF queueTime=0, state=WAITING, sendCnt=0, command=DefaultResponse [On/Off: 0000/0 -> 7776/1, cluster=0006, TID=41, commandIdentifier=253, statusCode=FAILURE]]
23:09:20.966 [DEBUG] [.transaction.ZigBeeTransactionManager] - Transaction Manager: Send Next transaction. outstandingTransactions=0, outstandingQueues=1, sleepy=0/3
23:09:20.967 [DEBUG] [.transaction.ZigBeeTransactionManager] - 7776/1: Sending ZigBeeTransaction [ieeeAddress=BC33ACFFFE615CDF queueTime=2, state=WAITING, sendCnt=0, command=DefaultResponse [On/Off: 0000/0 -> 7776/1, cluster=0006, TID=41, commandIdentifier=253, statusCode=FAILURE]]
23:09:20.968 [DEBUG] [.transaction.ZigBeeTransactionManager] - transactionListenerAdded: 1 outstanding
23:09:20.969 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX CMD: DefaultResponse [On/Off: 0000/0 -> 7776/1, cluster=0006, TID=41, commandIdentifier=253, statusCode=FAILURE]
23:09:20.969 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=65, commandId=11]
23:09:20.970 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0000/1, destinationAddress=7776/1, profile=0104, cluster=0006, addressMode=DEVICE, radius=8, apsSecurity=false, ackRequest=true, apsCounter=B1, rssi=--, lqi=--, payload=18 41 0B FD 01]
23:09:20.971 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH TX EZSP: EzspSendUnicastRequest [networkId=0, type=EMBER_OUTGOING_DIRECT, indexOrDestination=7776, apsFrame=EmberApsFrame [profileId=0104, clusterId=0006, sourceEndpoint=1, destinationEndpoint=1, options=[EMBER_APS_OPTION_RETRY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY], groupId=0, sequence=B1], messageTag=41, messageContents=18 41 0B FD 01]
23:09:20.972 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - Incoming message from node 7776 did not translate to command
23:09:20.973 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue size: 1
23:09:20.975 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=2, ackNum=4, reTx=false, data=9B 00 01 34 00 00 76 77 04 01 06 00 01 01 40 11 00 00 B1 41 05 18 41 0B FD 01]
23:09:21.054 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=4, ackNum=3, reTx=false, data=9B 80 01 34 00 00 3D]
23:09:21.055 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=2, ackNum=4, reTx=false, data=9B 00 01 34 00 00 76 77 04 01 06 00 01 01 40 11 00 00 B1 41 05 18 41 0B FD 01]
23:09:21.056 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
23:09:21.056 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
23:09:21.057 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=5, notRdy=false]
23:09:21.057 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspSendUnicastResponse [networkId=0, status=EMBER_SUCCESS, sequence=3D]
23:09:21.125 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=5, ackNum=3, reTx=false, data=9B 90 01 59 00 76 77 DF 5C 61 FE FF AC 33 BC C8 CE 01 00 00]
23:09:21.126 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
23:09:21.126 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
23:09:21.127 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=6, notRdy=false]
23:09:21.127 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingRouteRecordHandler [networkId=0, source=7776, sourceEui=BC33ACFFFE615CDF, lastHopLqi=200, lastHopRssi=-50, relayList=0000]
23:09:21.170 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=6, ackNum=3, reTx=false, data=9B 90 01 3F 00 00 76 77 04 01 06 00 01 01 40 11 00 00 3D 41 00 00]
23:09:21.171 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
23:09:21.171 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
23:09:21.171 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=7, notRdy=false]
23:09:21.171 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspMessageSentHandler [networkId=0, type=EMBER_OUTGOING_DIRECT, indexOrDestination=7776, apsFrame=EmberApsFrame [profileId=0104, clusterId=0006, sourceEndpoint=1, destinationEndpoint=1, options=[EMBER_APS_OPTION_RETRY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY], groupId=0, sequence=3D], messageTag=41, status=EMBER_SUCCESS, messageContents=]
23:09:21.172 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX STA: msgTag=41 state=RX_ACK
23:09:21.173 [DEBUG] [.transaction.ZigBeeTransactionManager] - notifyTransactionProgress: TID=41, state=RX_ACK, outstanding=1
23:09:21.174 [DEBUG] [.transaction.ZigBeeTransactionManager] - Transaction complete: ZigBeeTransaction [ieeeAddress=BC33ACFFFE615CDF queueTime=209, state=COMPLETE, sendCnt=1, command=DefaultResponse [On/Off: 0000/0 -> 7776/1, cluster=0006, TID=41, commandIdentifier=253, statusCode=FAILURE]]
23:09:21.175 [DEBUG] [.transaction.ZigBeeTransactionManager] - transactionListenerRemoved: 0 outstanding
23:09:21.176 [DEBUG] [ee.transaction.ZigBeeTransactionQueue] - BC33ACFFFE615CDF: transactionComplete, state=COMPLETE, outstanding=0
23:09:21.177 [DEBUG] [.transaction.ZigBeeTransactionManager] - Transaction Manager: Send Next transaction. outstandingTransactions=0, outstandingQueues=0, sleepy=0/3
23:09:21.178 [DEBUG] [.zigbee.transaction.ZigBeeTransaction] - Transaction state changed: nwk=7776, TID=41, event=RX_ACK, state=COMPLETE
23:09:22.493 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue size: 1
23:09:22.495 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=3, ackNum=7, reTx=false, data=9C 00 01 18 00]
23:09:22.544 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=7, ackNum=4, reTx=false, data=9C 80 01 18 00 02]
23:09:22.545 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=3, ackNum=7, reTx=false, data=9C 00 01 18 00]
23:09:22.546 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
23:09:22.546 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
23:09:22.546 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=0, notRdy=false]
23:09:23.356 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=0, ackNum=4, reTx=false, data=9C 90 01 3F 00 03 FD FF 04 01 08 00 01 FF 00 01 00 00 07 00 00 00]
23:09:23.357 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
23:09:23.357 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
23:09:23.358 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=1, notRdy=false]
23:09:23.358 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspMessageSentHandler [networkId=0, type=EMBER_OUTGOING_MULTICAST, indexOrDestination=FFFD, apsFrame=EmberApsFrame [profileId=0104, clusterId=0008, sourceEndpoint=1, destinationEndpoint=255, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=07], messageTag=00, status=EMBER_SUCCESS, messageContents=]
23:09:23.359 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX STA: msgTag=00 state=RX_ACK
23:09:23.360 [DEBUG] [.transaction.ZigBeeTransactionManager] - notifyTransactionProgress: TID=00, state=RX_ACK, outstanding=0
23:09:23.496 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue size: 1
23:09:23.497 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=4, ackNum=1, reTx=false, data=9D 00 01 18 00]
23:09:23.523 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=1, ackNum=5, reTx=false, data=9D 80 01 18 00 02]
23:09:23.525 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=4, ackNum=1, reTx=false, data=9D 00 01 18 00]
23:09:23.526 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
23:09:23.526 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
23:09:23.527 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=2, notRdy=false]
23:09:24.498 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue size: 1
23:09:24.499 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=5, ackNum=2, reTx=false, data=9E 00 01 18 00]
23:09:24.554 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=2, ackNum=6, reTx=false, data=9E 80 01 18 00 02]
23:09:24.556 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=5, ackNum=2, reTx=false, data=9E 00 01 18 00]
23:09:24.556 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
23:09:24.556 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
23:09:24.558 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=3, notRdy=false]
23:09:24.821 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=3, ackNum=6, reTx=false, data=9E 90 01 3F 00 03 FD FF 04 01 08 00 01 FF 00 01 00 00 08 00 00 00]
23:09:24.823 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
23:09:24.823 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
23:09:24.824 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=4, notRdy=false]
23:09:24.825 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspMessageSentHandler [networkId=0, type=EMBER_OUTGOING_MULTICAST, indexOrDestination=FFFD, apsFrame=EmberApsFrame [profileId=0104, clusterId=0008, sourceEndpoint=1, destinationEndpoint=255, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=08], messageTag=00, status=EMBER_SUCCESS, messageContents=]
23:09:24.826 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX STA: msgTag=00 state=RX_ACK
23:09:24.827 [DEBUG] [.transaction.ZigBeeTransactionManager] - notifyTransactionProgress: TID=00, state=RX_ACK, outstanding=0
23:09:25.500 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue size: 1
23:09:25.501 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=6, ackNum=4, reTx=false, data=9F 00 01 18 00]
23:09:25.527 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=4, ackNum=7, reTx=false, data=9F 80 01 18 00 02]
23:09:25.528 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=6, ackNum=4, reTx=false, data=9F 00 01 18 00]
23:09:25.529 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
23:09:25.530 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=5, notRdy=false]
23:09:25.530 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
23:09:26.364 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=5, ackNum=7, reTx=false, data=9F 90 01 3F 00 03 FD FF 04 01 08 00 01 FF 00 01 00 00 09 00 00 00]
23:09:26.365 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
23:09:26.365 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
23:09:26.365 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=6, notRdy=false]
23:09:26.366 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspMessageSentHandler [networkId=0, type=EMBER_OUTGOING_MULTICAST, indexOrDestination=FFFD, apsFrame=EmberApsFrame [profileId=0104, clusterId=0008, sourceEndpoint=1, destinationEndpoint=255, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=09], messageTag=00, status=EMBER_SUCCESS, messageContents=]
23:09:26.366 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX STA: msgTag=00 state=RX_ACK
23:09:26.367 [DEBUG] [.transaction.ZigBeeTransactionManager] - notifyTransactionProgress: TID=00, state=RX_ACK, outstanding=0
23:09:26.502 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue size: 1
23:09:26.503 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=7, ackNum=6, reTx=false, data=A0 00 01 18 00]
23:09:26.530 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=6, ackNum=0, reTx=false, data=A0 80 01 18 00 02]
23:09:26.531 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=7, ackNum=6, reTx=false, data=A0 00 01 18 00]
23:09:26.532 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
23:09:26.532 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
23:09:26.532 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=7, notRdy=false]
23:09:27.504 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue size: 1
23:09:27.506 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=0, ackNum=7, reTx=false, data=A1 00 01 18 00]
23:09:27.532 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=7, ackNum=1, reTx=false, data=A1 80 01 18 00 02]
23:09:27.533 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=0, ackNum=7, reTx=false, data=A1 00 01 18 00]
23:09:27.534 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
23:09:27.534 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
23:09:27.535 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=0, notRdy=false]
23:09:27.615 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=0, ackNum=1, reTx=false, data=A1 90 01 3F 00 03 FD FF 04 01 08 00 01 FF 00 01 00 00 0A 00 00 00]
23:09:27.616 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
23:09:27.616 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
23:09:27.617 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=1, notRdy=false]
23:09:27.617 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspMessageSentHandler [networkId=0, type=EMBER_OUTGOING_MULTICAST, indexOrDestination=FFFD, apsFrame=EmberApsFrame [profileId=0104, clusterId=0008, sourceEndpoint=1, destinationEndpoint=255, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=0A], messageTag=00, status=EMBER_SUCCESS, messageContents=]
23:09:27.617 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX STA: msgTag=00 state=RX_ACK
23:09:27.618 [DEBUG] [.transaction.ZigBeeTransactionManager] - notifyTransactionProgress: TID=00, state=RX_ACK, outstanding=0
23:09:27.623 [INFO ] [openhab.event.ItemStateChangedEvent  ] - Item 'airsensor_Ausfuhrung' changed from OFF to ON
23:09:28.507 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue size: 1
23:09:28.508 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=1, ackNum=1, reTx=false, data=A2 00 01 18 00]
23:09:28.534 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=1, ackNum=2, reTx=false, data=A2 80 01 18 00 02]
23:09:28.534 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=1, ackNum=1, reTx=false, data=A2 00 01 18 00]
23:09:28.534 [INFO ] [openhab.event.ItemStateChangedEvent  ] - Item 'Localcomputer_Memory_Available' changed from 3299 to 3298
23:09:28.535 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
23:09:28.535 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
23:09:28.535 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=2, notRdy=false]