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:16:33.546 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue size: 1
23:16:33.547 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=1, ackNum=5, reTx=false, data=4A 00 01 18 00]
23:16:33.574 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=5, ackNum=2, reTx=false, data=4A 80 01 18 00 02]
23:16:33.574 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=1, ackNum=5, reTx=false, data=4A 00 01 18 00]
23:16:33.575 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
23:16:33.575 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
23:16:33.576 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=6, notRdy=false]
23:16:34.357 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=6, ackNum=2, reTx=false, data=4A 90 01 3F 00 03 FD FF 04 01 06 00 01 FF 00 01 00 00 0B 00 00 00]
23:16:34.358 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
23:16:34.358 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
23:16:34.358 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=7, notRdy=false]
23:16:34.359 [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=0B], messageTag=00, status=EMBER_SUCCESS, messageContents=]
23:16:34.359 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX STA: msgTag=00 state=RX_ACK
23:16:34.360 [DEBUG] [.transaction.ZigBeeTransactionManager] - notifyTransactionProgress: TID=00, state=RX_ACK, outstanding=0
23:16:34.548 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue size: 1
23:16:34.549 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=2, ackNum=7, reTx=false, data=4B 00 01 18 00]
23:16:34.604 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=7, ackNum=3, reTx=false, data=4B 80 01 18 00 02]
23:16:34.605 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=2, ackNum=7, reTx=false, data=4B 00 01 18 00]
23:16:34.605 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
23:16:34.605 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
23:16:34.606 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=0, notRdy=false]
23:16:35.551 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue size: 1
23:16:35.552 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=3, ackNum=0, reTx=false, data=4C 00 01 18 00]
23:16:35.578 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=0, ackNum=4, reTx=false, data=4C 80 01 18 00 02]
23:16:35.579 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=3, ackNum=0, reTx=false, data=4C 00 01 18 00]
23:16:35.580 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
23:16:35.580 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
23:16:35.580 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=1, notRdy=false]
23:16:36.163 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=1, ackNum=4, reTx=false, data=4C 90 01 3F 00 03 FD FF 04 01 06 00 01 FF 00 01 00 00 0C 00 00 00]
23:16:36.164 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
23:16:36.164 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
23:16:36.165 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=2, notRdy=false]
23:16:36.165 [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=0C], messageTag=00, status=EMBER_SUCCESS, messageContents=]
23:16:36.166 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX STA: msgTag=00 state=RX_ACK
23:16:36.167 [DEBUG] [.transaction.ZigBeeTransactionManager] - notifyTransactionProgress: TID=00, state=RX_ACK, outstanding=0
23:16:36.553 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue size: 1
23:16:36.553 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=4, ackNum=2, reTx=false, data=4D 00 01 18 00]
23:16:36.579 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=2, ackNum=5, reTx=false, data=4D 80 01 18 00 02]
23:16:36.580 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=4, ackNum=2, reTx=false, data=4D 00 01 18 00]
23:16:36.582 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
23:16:36.582 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
23:16:36.583 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=3, notRdy=false]
23:16:37.554 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue size: 1
23:16:37.555 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=5, ackNum=3, reTx=false, data=4E 00 01 18 00]
23:16:37.582 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=3, ackNum=6, reTx=false, data=4E 80 01 18 00 02]
23:16:37.583 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=5, ackNum=3, reTx=false, data=4E 00 01 18 00]
23:16:37.583 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
23:16:37.583 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
23:16:37.584 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=4, notRdy=false]
23:16:38.239 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=4, ackNum=6, reTx=false, data=4E 90 01 3F 00 03 FD FF 04 01 06 00 01 FF 00 01 00 00 0D 00 00 00]
23:16:38.244 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
23:16:38.244 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
23:16:38.245 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=5, notRdy=false]
23:16:38.245 [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=0D], messageTag=00, status=EMBER_SUCCESS, messageContents=]
23:16:38.246 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX STA: msgTag=00 state=RX_ACK
23:16:38.249 [DEBUG] [.transaction.ZigBeeTransactionManager] - notifyTransactionProgress: TID=00, state=RX_ACK, outstanding=0
23:16:38.557 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue size: 1
23:16:38.559 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=6, ackNum=5, reTx=false, data=4F 00 01 18 00]
23:16:38.614 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=5, ackNum=7, reTx=false, data=4F 80 01 18 00 02]
23:16:38.615 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=6, ackNum=5, reTx=false, data=4F 00 01 18 00]
23:16:38.616 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
23:16:38.616 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
23:16:38.618 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=6, notRdy=false]
23:16:39.355 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=6, ackNum=7, reTx=false, data=4F 90 01 59 00 76 77 DF 5C 61 FE FF AC 33 BC CC CF 01 00 00]
23:16:39.356 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
23:16:39.356 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
23:16:39.357 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=7, notRdy=false]
23:16:39.357 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingRouteRecordHandler [networkId=0, source=7776, sourceEui=BC33ACFFFE615CDF, lastHopLqi=204, lastHopRssi=-49, relayList=0000]
23:16:39.402 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=7, ackNum=7, reTx=false, data=4F 90 01 45 00 00 04 01 06 00 01 01 00 01 00 00 0E CC CF 76 77 FF FF 04 01 49 FD 00 04]
23:16:39.403 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
23:16:39.403 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
23:16:39.404 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=0, notRdy=false]
23:16:39.404 [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=0E], lastHopLqi=204, lastHopRssi=-49, sender=7776, bindingIndex=255, addressIndex=255, messageContents=01 49 FD 00]
23:16:39.405 [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=0E, rssi=-49, lqi=CC, payload=01 49 FD 00]
23:16:39.406 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFE615CDF: Node update. NWK Address=7776
23:16:39.407 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFE615CDF: Node 7776 is not updated
23:16:39.408 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=CLUSTER_SPECIFIC_COMMAND, manufacturerSpecific=false, direction=CLIENT_TO_SERVER, disableDefaultResponse=false, manufacturerCode=0, sequenceNumber=73, commandId=253]
23:16:39.410 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - BC33ACFFFE615CDF: Unknown command 253
23:16:39.411 [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=49, commandIdentifier=253, statusCode=FAILURE]]
23:16:39.412 [DEBUG] [.transaction.ZigBeeTransactionManager] - Transaction Manager: Send Next transaction. outstandingTransactions=0, outstandingQueues=1, sleepy=0/3
23:16:39.413 [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=49, commandIdentifier=253, statusCode=FAILURE]]
23:16:39.414 [DEBUG] [.transaction.ZigBeeTransactionManager] - transactionListenerAdded: 1 outstanding
23:16:39.415 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX CMD: DefaultResponse [On/Off: 0000/0 -> 7776/1, cluster=0006, TID=49, commandIdentifier=253, statusCode=FAILURE]
23:16:39.417 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=73, commandId=11]
23:16:39.418 [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=B2, rssi=--, lqi=--, payload=18 49 0B FD 01]
23:16:39.419 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - Incoming message from node 7776 did not translate to command
23:16:39.420 [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=B2], messageTag=49, messageContents=18 49 0B FD 01]
23:16:39.421 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue size: 1
23:16:39.422 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=7, ackNum=0, reTx=false, data=50 00 01 34 00 00 76 77 04 01 06 00 01 01 40 11 00 00 B2 49 05 18 49 0B FD 01]
23:16:39.494 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=0, ackNum=0, reTx=false, data=50 80 01 34 00 00 3E]
23:16:39.496 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=7, ackNum=0, reTx=false, data=50 00 01 34 00 00 76 77 04 01 06 00 01 01 40 11 00 00 B2 49 05 18 49 0B FD 01]
23:16:39.497 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
23:16:39.497 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
23:16:39.498 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=1, notRdy=false]
23:16:39.498 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspSendUnicastResponse [networkId=0, status=EMBER_SUCCESS, sequence=3E]
23:16:39.564 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=1, ackNum=0, reTx=false, data=50 90 01 59 00 76 77 DF 5C 61 FE FF AC 33 BC CC CF 01 00 00]
23:16:39.565 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
23:16:39.565 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
23:16:39.566 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingRouteRecordHandler [networkId=0, source=7776, sourceEui=BC33ACFFFE615CDF, lastHopLqi=204, lastHopRssi=-49, relayList=0000]
23:16:39.566 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=2, notRdy=false]
23:16:39.608 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=2, ackNum=0, reTx=false, data=50 90 01 3F 00 00 76 77 04 01 06 00 01 01 40 11 00 00 3E 49 00 00]
23:16:39.609 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
23:16:39.609 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
23:16:39.610 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=3, notRdy=false]
23:16:39.610 [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=3E], messageTag=49, status=EMBER_SUCCESS, messageContents=]
23:16:39.611 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX STA: msgTag=49 state=RX_ACK
23:16:39.612 [DEBUG] [.transaction.ZigBeeTransactionManager] - notifyTransactionProgress: TID=49, state=RX_ACK, outstanding=1
23:16:39.614 [DEBUG] [.transaction.ZigBeeTransactionManager] - Transaction complete: ZigBeeTransaction [ieeeAddress=BC33ACFFFE615CDF queueTime=203, state=COMPLETE, sendCnt=1, command=DefaultResponse [On/Off: 0000/0 -> 7776/1, cluster=0006, TID=49, commandIdentifier=253, statusCode=FAILURE]]
23:16:39.615 [DEBUG] [.transaction.ZigBeeTransactionManager] - transactionListenerRemoved: 0 outstanding
23:16:39.616 [DEBUG] [ee.transaction.ZigBeeTransactionQueue] - BC33ACFFFE615CDF: transactionComplete, state=COMPLETE, outstanding=0
23:16:39.617 [DEBUG] [.transaction.ZigBeeTransactionManager] - Transaction Manager: Send Next transaction. outstandingTransactions=0, outstandingQueues=0, sleepy=0/3
23:16:39.617 [DEBUG] [.zigbee.transaction.ZigBeeTransaction] - Transaction state changed: nwk=7776, TID=49, event=RX_ACK, state=COMPLETE
23:16:40.560 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue size: 1
23:16:40.561 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=0, ackNum=3, reTx=false, data=51 00 01 18 00]
23:16:40.614 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=3, ackNum=1, reTx=false, data=51 80 01 18 00 02]
23:16:40.615 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=0, ackNum=3, reTx=false, data=51 00 01 18 00]
23:16:40.616 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
23:16:40.616 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
23:16:40.616 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=4, notRdy=false]
23:16:41.376 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=4, ackNum=1, reTx=false, data=51 90 01 3F 00 03 FD FF 04 01 08 00 01 FF 00 01 00 00 0F 00 00 00]
23:16:41.377 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
23:16:41.377 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
23:16:41.378 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=5, notRdy=false]
23:16:41.378 [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=0F], messageTag=00, status=EMBER_SUCCESS, messageContents=]
23:16:41.379 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX STA: msgTag=00 state=RX_ACK
23:16:41.380 [DEBUG] [.transaction.ZigBeeTransactionManager] - notifyTransactionProgress: TID=00, state=RX_ACK, outstanding=0
23:16:41.562 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue size: 1
23:16:41.563 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=1, ackNum=5, reTx=false, data=52 00 01 18 00]
23:16:41.590 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=5, ackNum=2, reTx=false, data=52 80 01 18 00 02]
23:16:41.591 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=1, ackNum=5, reTx=false, data=52 00 01 18 00]
23:16:41.591 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
23:16:41.591 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
23:16:41.592 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=6, notRdy=false]
23:16:42.565 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue size: 1
23:16:42.566 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=2, ackNum=6, reTx=false, data=53 00 01 18 00]
23:16:42.592 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=6, ackNum=3, reTx=false, data=53 80 01 18 00 02]
23:16:42.593 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=2, ackNum=6, reTx=false, data=53 00 01 18 00]
23:16:42.593 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
23:16:42.594 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
23:16:42.594 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=7, notRdy=false]
23:16:43.004 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=7, ackNum=3, reTx=false, data=53 90 01 3F 00 03 FD FF 04 01 08 00 01 FF 00 01 00 00 10 00 00 00]
23:16:43.005 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
23:16:43.006 [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=10], messageTag=00, status=EMBER_SUCCESS, messageContents=]
23:16:43.007 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 0
23:16:43.007 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX STA: msgTag=00 state=RX_ACK
23:16:43.008 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=0, notRdy=false]
23:16:43.008 [DEBUG] [.transaction.ZigBeeTransactionManager] - notifyTransactionProgress: TID=00, state=RX_ACK, outstanding=0
23:16:43.307 [INFO ] [openhab.event.ItemStateChangedEvent  ] - Item 'Localcomputer_Memory_Available' changed from 3299 to 3298
23:16:43.567 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue size: 1
23:16:43.569 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=3, ackNum=0, reTx=false, data=54 00 01 18 00]
23:16:43.624 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=0, ackNum=4, reTx=false, data=54 80 01 18 00 02]
23:16:43.625 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=3, ackNum=0, reTx=false, data=54 00 01 18 00]
23:16:43.625 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
23:16:43.626 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
23:16:43.626 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=1, notRdy=false]
23:16:44.365 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=1, ackNum=4, reTx=false, data=54 90 01 3F 00 03 FD FF 04 01 08 00 01 FF 00 01 00 00 11 00 00 00]
23:16:44.366 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
23:16:44.366 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
23:16:44.366 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=2, notRdy=false]
23:16:44.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=11], messageTag=00, status=EMBER_SUCCESS, messageContents=]
23:16:44.367 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX STA: msgTag=00 state=RX_ACK
23:16:44.367 [DEBUG] [.transaction.ZigBeeTransactionManager] - notifyTransactionProgress: TID=00, state=RX_ACK, outstanding=0
23:16:44.570 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue size: 1
23:16:44.571 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=4, ackNum=2, reTx=false, data=55 00 01 18 00]
23:16:44.598 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=2, ackNum=5, reTx=false, data=55 80 01 18 00 02]
23:16:44.598 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=4, ackNum=2, reTx=false, data=55 00 01 18 00]
23:16:44.599 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
23:16:44.599 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
23:16:44.600 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=3, notRdy=false]
23:16:45.572 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue size: 1
23:16:45.576 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=5, ackNum=3, reTx=false, data=56 00 01 18 00]
23:16:45.624 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=3, ackNum=6, reTx=false, data=56 80 01 18 00 02]
23:16:45.625 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=5, ackNum=3, reTx=false, data=56 00 01 18 00]
23:16:45.626 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
23:16:45.626 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
23:16:45.626 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=4, notRdy=false]
23:16:46.009 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=4, ackNum=6, reTx=false, data=56 90 01 3F 00 03 FD FF 04 01 08 00 01 FF 00 01 00 00 12 00 00 00]
23:16:46.010 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
23:16:46.010 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
23:16:46.011 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=5, notRdy=false]
23:16:46.011 [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=12], messageTag=00, status=EMBER_SUCCESS, messageContents=]
23:16:46.012 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX STA: msgTag=00 state=RX_ACK
23:16:46.012 [DEBUG] [.transaction.ZigBeeTransactionManager] - notifyTransactionProgress: TID=00, state=RX_ACK, outstanding=0
23:16:46.078 [INFO ] [openhab.event.ItemStateChangedEvent  ] - Item 'airsensor_Ausfuhrung' changed from OFF to ON
23:16:46.577 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue size: 1
23:16:46.578 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=6, ackNum=5, reTx=false, data=57 00 01 18 00]
23:16:46.604 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=5, ackNum=7, reTx=false, data=57 80 01 18 00 02]
23:16:46.604 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=6, ackNum=5, reTx=false, data=57 00 01 18 00]
23:16:46.605 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
23:16:46.605 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
23:16:46.605 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=6, notRdy=false]
23:16:47.579 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue size: 1
23:16:47.580 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=7, ackNum=6, reTx=false, data=58 00 01 18 00]
23:16:47.634 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=6, ackNum=0, reTx=false, data=58 80 01 18 00 02]
23:16:47.635 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=7, ackNum=6, reTx=false, data=58 00 01 18 00]
23:16:47.635 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH added EZSP frame to receive queue. Queue length 1
23:16:47.635 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH took EZSP frame from receive queue. Queue length 0
23:16:47.636 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=7, notRdy=false]