16:15:15.895 [DEBUG] [gbee.discovery.ZigBeeDiscoveryService] - Starting ZigBee scan for zigbee:coordinator_cc2531:c3fee9b6db
16:15:15.896 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - Permit join to FFFC/0 for 60 seconds.
16:15:15.896 [DEBUG] [ee.transaction.ZigBeeTransactionQueue] - Broadcast: Added transaction to queue, len=1, transaction=ZigBeeTransaction [ieeeAddress=null queueTime=0, state=WAITING, sendCnt=0, command=ManagementPermitJoiningRequest [0000/0 -> FFFC/0, cluster=0036, TID=--, permitDuration=60, tcSignificance=true]]
16:15:15.896 [DEBUG] [.transaction.ZigBeeTransactionManager] - Transaction Manager: Send Next transaction. outstandingTransactions=0, outstandingQueues=1, sleepy=0/3
16:15:15.897 [DEBUG] [.transaction.ZigBeeTransactionManager] - FFFC/0: Sending ZigBeeTransaction [ieeeAddress=null queueTime=0, state=WAITING, sendCnt=0, command=ManagementPermitJoiningRequest [0000/0 -> FFFC/0, cluster=0036, TID=03, permitDuration=60, tcSignificance=true]]
16:15:15.897 [DEBUG] [.transaction.ZigBeeTransactionManager] - transactionListenerAdded: 1 outstanding
16:15:15.897 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ManagementPermitJoiningRequest [0000/0 -> FFFC/0, cluster=0036, TID=03, permitDuration=60, tcSignificance=true]
16:15:15.897 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0000/0, destinationAddress=FFFC/0, profile=0000, cluster=0036, addressMode=DEVICE, radius=8, apsSecurity=false, ackRequest=true, apsCounter=04, rssi=--, lqi=--, payload=03 3C 01]
16:15:15.897 [DEBUG] [ee.transaction.ZigBeeTransactionQueue] - 00124B001CD48642: Added transaction to queue, len=1, transaction=ZigBeeTransaction [ieeeAddress=00124B001CD48642 queueTime=0, state=WAITING, sendCnt=0, command=ManagementPermitJoiningRequest [0000/0 -> 0000/0, cluster=0036, TID=--, permitDuration=60, tcSignificance=true]]
16:15:15.897 [DEBUG] [531.network.impl.CommandInterfaceImpl] - ->  AF_DATA_REQUEST (Packet: subsystem=null, length=13, apiId=24 01, data=FE 0D 24 01 FC FF 00 00 36 00 04 30 08 03 03 3C 01 1C, checksum=1C, error=false)
16:15:15.898 [DEBUG] [.transaction.ZigBeeTransactionManager] - Transaction Manager: Send Next transaction. outstandingTransactions=1, outstandingQueues=1, sleepy=0/3
16:15:15.898 [DEBUG] [.transaction.ZigBeeTransactionManager] - 0000/0: Sending ZigBeeTransaction [ieeeAddress=00124B001CD48642 queueTime=1, state=WAITING, sendCnt=0, command=ManagementPermitJoiningRequest [0000/0 -> 0000/0, cluster=0036, TID=04, permitDuration=60, tcSignificance=true]]
16:15:15.898 [DEBUG] [.transaction.ZigBeeTransactionManager] - transactionListenerAdded: 2 outstanding
16:15:15.898 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ManagementPermitJoiningRequest [0000/0 -> 0000/0, cluster=0036, TID=04, permitDuration=60, tcSignificance=true]
16:15:15.898 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0000/0, destinationAddress=0000/0, profile=0000, cluster=0036, addressMode=DEVICE, radius=8, apsSecurity=false, ackRequest=true, apsCounter=05, rssi=--, lqi=--, payload=04 3C 01]
16:15:15.916 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-  AF_DATA_SRSP (FE 01 64 01 00 64)
16:15:15.916 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX STA: msgTag=03 state=TX_ACK
16:15:15.916 [DEBUG] [.transaction.ZigBeeTransactionManager] - notifyTransactionProgress: TID=03, state=TX_ACK, outstanding=2
16:15:15.966 [DEBUG] [531.network.impl.CommandInterfaceImpl] - ->  AF_DATA_REQUEST (Packet: subsystem=null, length=13, apiId=24 01, data=FE 0D 24 01 00 00 00 00 36 00 05 30 08 03 04 3C 01 19, checksum=19, error=false)
16:15:15.966 [DEBUG] [.transaction.ZigBeeTransactionManager] - Transaction complete: ZigBeeTransaction [ieeeAddress=null queueTime=70, state=COMPLETE, sendCnt=1, command=ManagementPermitJoiningRequest [0000/0 -> FFFC/0, cluster=0036, TID=03, permitDuration=60, tcSignificance=true]]
16:15:15.966 [DEBUG] [.transaction.ZigBeeTransactionManager] - transactionListenerRemoved: 1 outstanding
16:15:15.966 [DEBUG] [ee.transaction.ZigBeeTransactionQueue] - Broadcast: transactionComplete, state=COMPLETE, outstanding=0
16:15:15.966 [DEBUG] [.transaction.ZigBeeTransactionManager] - Transaction Manager: Send Next transaction. outstandingTransactions=1, outstandingQueues=0, sleepy=0/3
16:15:15.966 [DEBUG] [.zigbee.transaction.ZigBeeTransaction] - Transaction state changed: nwk=FFFC, TID=03, event=TX_ACK, state=COMPLETE
16:15:15.979 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-  AF_DATA_SRSP (FE 01 64 01 00 64)
16:15:15.979 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-- ZToolPacket (FE 01 45 CB 3C B3)
16:15:15.979 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX STA: msgTag=04 state=TX_ACK
16:15:15.979 [DEBUG] [531.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=1, apiId=45 CB, data=FE 01 45 CB 3C B3, checksum=B3, error=false
16:15:15.979 [DEBUG] [ee.dongle.cc2531.ZigBeeDongleTiCc2531] - Unhandled ZToolPacket type 0x45cb
16:15:15.979 [DEBUG] [.transaction.ZigBeeTransactionManager] - notifyTransactionProgress: TID=04, state=TX_ACK, outstanding=1
16:15:15.980 [DEBUG] [.transaction.ZigBeeTransactionManager] - Transaction complete: ZigBeeTransaction [ieeeAddress=00124B001CD48642 queueTime=83, state=COMPLETE, sendCnt=1, command=ManagementPermitJoiningRequest [0000/0 -> 0000/0, cluster=0036, TID=04, permitDuration=60, tcSignificance=true]]
16:15:15.980 [DEBUG] [.transaction.ZigBeeTransactionManager] - transactionListenerRemoved: 0 outstanding
16:15:15.980 [DEBUG] [ee.transaction.ZigBeeTransactionQueue] - 00124B001CD48642: transactionComplete, state=COMPLETE, outstanding=0
16:15:15.980 [DEBUG] [.transaction.ZigBeeTransactionManager] - Transaction Manager: Send Next transaction. outstandingTransactions=0, outstandingQueues=0, sleepy=0/3
16:15:15.980 [DEBUG] [.zigbee.transaction.ZigBeeTransaction] - Transaction state changed: nwk=0000, TID=04, event=TX_ACK, state=COMPLETE
16:15:15.994 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-- ZToolPacket (FE 03 45 B6 00 00 00 F0)
16:15:15.994 [DEBUG] [531.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=3, apiId=45 B6, data=FE 03 45 B6 00 00 00 F0, checksum=F0, error=false
16:15:16.000 [DEBUG] [ee.dongle.cc2531.ZigBeeDongleTiCc2531] - Unhandled ZToolPacket type 0x45b6
16:15:16.002 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-- ZToolPacket (FE 0A 45 FF 00 00 00 36 80 00 04 00 00 00 02)
16:15:16.003 [DEBUG] [531.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=10, apiId=45 FF, data=FE 0A 45 FF 00 00 00 36 80 00 04 00 00 00 02, checksum=02, error=false
16:15:16.003 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=0000/0, destinationAddress=0000/0, profile=0000, cluster=8036, addressMode=null, radius=0, apsSecurity=false, ackRequest=false, apsCounter=--, rssi=--, lqi=--, payload=00 00]
16:15:16.003 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ManagementPermitJoiningResponse [0000/0 -> 0000/0, cluster=8036, TID=00, status=SUCCESS]
16:15:16.003 [DEBUG] [.transaction.ZigBeeTransactionManager] - notifyTransactionCommand: ManagementPermitJoiningResponse [0000/0 -> 0000/0, cluster=8036, TID=00, status=SUCCESS]
16:15:17.444 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-- ZToolPacket (FE 0C 45 CA D1 ED 9A FD 00 0A AA 3E B0 7C 00 00 8A)
16:15:17.444 [DEBUG] [531.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=12, apiId=45 CA, data=FE 0C 45 CA D1 ED 9A FD 00 0A AA 3E B0 7C 00 00 8A, checksum=8A, error=false
16:15:17.444 [DEBUG] [ee.dongle.cc2531.ZigBeeDongleTiCc2531] - Unhandled ZToolPacket type 0x45ca
16:15:17.864 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-- ZToolPacket (FE 0D 45 C1 D1 ED D1 ED 9A FD 00 0A AA 3E B0 7C 8E 32)
16:15:17.865 [DEBUG] [531.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=13, apiId=45 C1, data=FE 0D 45 C1 D1 ED D1 ED 9A FD 00 0A AA 3E B0 7C 8E 32, checksum=32, error=false
16:15:17.867 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=EDD1/0, destinationAddress=0000/0, profile=0000, cluster=0013, addressMode=null, radius=0, apsSecurity=false, ackRequest=false, apsCounter=--, rssi=--, lqi=--, payload=ED D1 ED 9A FD 00 0A AA 3E B0 7C 8E]
16:15:17.868 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - Incoming message from unknown node EDD1: Notifying announce listeners
16:15:17.869 [DEBUG] [app.discovery.ZigBeeNetworkDiscoverer] - NWK Discovery for EDD1 scheduling node discovery
16:15:17.869 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX CMD: DeviceAnnounce [EDD1/0 -> 0000/0, cluster=0013, TID=ED, nwkAddrOfInterest=EDD1, ieeeAddr=7CB03EAA0A00FD9A, capability=8E]
16:15:17.870 [DEBUG] [app.discovery.ZigBeeNetworkDiscoverer] - NWK Discovery for EDD1 starting node discovery
16:15:17.870 [DEBUG] [.transaction.ZigBeeTransactionManager] - notifyTransactionCommand: DeviceAnnounce [EDD1/0 -> 0000/0, cluster=0013, TID=ED, nwkAddrOfInterest=EDD1, ieeeAddr=7CB03EAA0A00FD9A, capability=8E]
16:15:17.870 [DEBUG] [.transaction.ZigBeeTransactionManager] - Attempt to send command with unknown destination: ZigBeeTransaction [ieeeAddress=null queueTime=-, state=WAITING, sendCnt=0, command=IeeeAddressRequest [0000/0 -> EDD1/0, cluster=0001, TID=--, nwkAddrOfInterest=EDD1, requestType=0, startIndex=0]]
16:15:17.870 [DEBUG] [app.discovery.ZigBeeNetworkDiscoverer] - 7CB03EAA0A00FD9A: Device announce received. From EDD1, for EDD1
16:15:17.870 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-- ZToolPacket (FE 14 45 FF D1 ED 01 13 00 00 00 FF FF D1 ED 9A FD 00 0A AA 3E B0 7C 8E 07)
16:15:17.870 [DEBUG] [ee.transaction.ZigBeeTransactionQueue] - Default: Added transaction to queue, len=1, transaction=ZigBeeTransaction [ieeeAddress=null queueTime=0, state=WAITING, sendCnt=0, command=IeeeAddressRequest [0000/0 -> EDD1/0, cluster=0001, TID=--, nwkAddrOfInterest=EDD1, requestType=0, startIndex=0]]
16:15:17.870 [DEBUG] [app.discovery.ZigBeeNetworkDiscoverer] - 7CB03EAA0A00FD9A: NWK Discovery add node EDD1
16:15:17.870 [DEBUG] [531.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=20, apiId=45 FF, data=FE 14 45 FF D1 ED 01 13 00 00 00 FF FF D1 ED 9A FD 00 0A AA 3E B0 7C 8E 07, checksum=07, error=false
16:15:17.870 [DEBUG] [.transaction.ZigBeeTransactionManager] - Transaction Manager: Send Next transaction. outstandingTransactions=0, outstandingQueues=1, sleepy=0/3
16:15:17.870 [DEBUG] [com.zsmartsystems.zigbee.ZigBeeNode  ] - 7CB03EAA0A00FD9A: Node state updated from UNKNOWN to ONLINE
16:15:17.870 [DEBUG] [.transaction.ZigBeeTransactionManager] - EDD1/0: Sending ZigBeeTransaction [ieeeAddress=null queueTime=0, state=WAITING, sendCnt=0, command=IeeeAddressRequest [0000/0 -> EDD1/0, cluster=0001, TID=05, nwkAddrOfInterest=EDD1, requestType=0, startIndex=0]]
16:15:17.871 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - 7CB03EAA0A00FD9A: Updating node NWK=EDD1
16:15:17.871 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=EDD1/0, destinationAddress=FFFF/0, profile=0000, cluster=0013, addressMode=null, radius=0, apsSecurity=false, ackRequest=false, apsCounter=--, rssi=--, lqi=--, payload=FF D1 ED 9A FD 00 0A AA 3E B0 7C 8E]
16:15:17.871 [DEBUG] [.transaction.ZigBeeTransactionManager] - transactionListenerAdded: 1 outstanding
16:15:17.871 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX CMD: DeviceAnnounce [EDD1/0 -> FFFF/0, cluster=0013, TID=FF, nwkAddrOfInterest=EDD1, ieeeAddr=7CB03EAA0A00FD9A, capability=8E]
16:15:17.871 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX CMD: IeeeAddressRequest [0000/0 -> EDD1/0, cluster=0001, TID=05, nwkAddrOfInterest=EDD1, requestType=0, startIndex=0]
16:15:17.871 [DEBUG] [.transaction.ZigBeeTransactionManager] - notifyTransactionCommand: DeviceAnnounce [EDD1/0 -> FFFF/0, cluster=0013, TID=FF, nwkAddrOfInterest=EDD1, ieeeAddr=7CB03EAA0A00FD9A, capability=8E]
16:15:17.871 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0000/0, destinationAddress=EDD1/0, profile=0000, cluster=0001, addressMode=DEVICE, radius=8, apsSecurity=false, ackRequest=true, apsCounter=06, rssi=--, lqi=--, payload=05 D1 ED 00 00]
16:15:17.871 [DEBUG] [531.network.impl.CommandInterfaceImpl] - ->  AF_DATA_REQUEST (Packet: subsystem=null, length=15, apiId=24 01, data=FE 0F 24 01 D1 ED 00 00 01 00 06 30 08 05 05 D1 ED 00 00 15, checksum=15, error=false)
16:15:17.891 [DEBUG] [app.discovery.ZigBeeNetworkDiscoverer] - 7CB03EAA0A00FD9A: Device announce received. From EDD1, for EDD1
16:15:17.891 [DEBUG] [app.discovery.ZigBeeNetworkDiscoverer] - 7CB03EAA0A00FD9A: NWK Discovery add node EDD1
16:15:17.892 [DEBUG] [com.zsmartsystems.zigbee.ZigBeeNode  ] - 7CB03EAA0A00FD9A: Node state updated from UNKNOWN to ONLINE
16:15:17.892 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - 7CB03EAA0A00FD9A: Updating node NWK=EDD1
16:15:17.892 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - 7CB03EAA0A00FD9A: Node update. NWK Address=EDD1
16:15:17.892 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - 7CB03EAA0A00FD9A: Node EDD1 is not updated
16:15:17.896 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-  AF_DATA_SRSP (FE 01 64 01 00 64)
16:15:17.896 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX STA: msgTag=05 state=TX_ACK
16:15:17.896 [DEBUG] [.transaction.ZigBeeTransactionManager] - notifyTransactionProgress: TID=05, state=TX_ACK, outstanding=1
16:15:17.897 [DEBUG] [.zigbee.transaction.ZigBeeTransaction] - Transaction state changed: nwk=EDD1, TID=05, event=TX_ACK, state=TRANSMITTED
16:15:17.898 [DEBUG] [database.ZigBeeNetworkDatabaseManager] - 7CB03EAA0A00FD9A: Data store: Deferring write for 250ms.
16:15:17.905 [DEBUG] [pp.discovery.ZigBeeDiscoveryExtension] - 7CB03EAA0A00FD9A: DISCOVERY Extension: Adding discoverer for node
16:15:17.905 [DEBUG] [discovery.ZigBeeNodeServiceDiscoverer] - 7CB03EAA0A00FD9A: Node SVC Discovery: created discoverer
16:15:17.906 [DEBUG] [discovery.ZigBeeNodeServiceDiscoverer] - 7CB03EAA0A00FD9A: Node SVC Discovery: start discovery
16:15:17.906 [DEBUG] [discovery.ZigBeeNodeServiceDiscoverer] - 7CB03EAA0A00FD9A: Node SVC Discovery: starting new tasks [ACTIVE_ENDPOINTS, NWK_ADDRESS, NODE_DESCRIPTOR, POWER_DESCRIPTOR]
16:15:17.912 [DEBUG] [discovery.ZigBeeNodeServiceDiscoverer] - 7CB03EAA0A00FD9A: Node SVC Discovery: scheduled [NWK_ADDRESS, POWER_DESCRIPTOR, NODE_DESCRIPTOR, ACTIVE_ENDPOINTS]
16:15:17.921 [INFO ] [gbee.discovery.ZigBeeDiscoveryService] - 7CB03EAA0A00FD9A: Starting ZigBee device discovery
16:15:17.921 [DEBUG] [gbee.discovery.ZigBeeDiscoveryService] - 7CB03EAA0A00FD9A: Creating ZigBee device zigbee:device with bridge zigbee:coordinator_cc2531:c3fee9b6db
16:15:17.922 [INFO ] [ig.discovery.internal.PersistentInbox] - Added new thing 'zigbee:device:c3fee9b6db:7cb03eaa0a00fd9a' to inbox.
16:15:17.922 [DEBUG] [gbee.discovery.ZigBeeDiscoveryService] - 7CB03EAA0A00FD9A: Node discovery not complete
16:15:17.922 [INFO ] [openhab.event.InboxAddedEvent        ] - Discovery Result with UID 'zigbee:device:c3fee9b6db:7cb03eaa0a00fd9a' has been added.
16:15:17.973 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-- ZToolPacket (FE 0D 45 81 00 9A FD 00 0A AA 3E B0 7C D1 ED 00 00 C0)
16:15:17.973 [DEBUG] [531.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=13, apiId=45 81, data=FE 0D 45 81 00 9A FD 00 0A AA 3E B0 7C D1 ED 00 00 C0, checksum=C0, error=false
16:15:17.974 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=EDD1/0, destinationAddress=0000/0, profile=0000, cluster=8001, addressMode=null, radius=0, apsSecurity=false, ackRequest=false, apsCounter=--, rssi=--, lqi=--, payload=00 00 9A FD 00 0A AA 3E B0 7C D1 ED 00 00]
16:15:17.974 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX CMD: IeeeAddressResponse [EDD1/0 -> 0000/0, cluster=8001, TID=00, status=SUCCESS, ieeeAddrRemoteDev=7CB03EAA0A00FD9A, nwkAddrRemoteDev=EDD1, startIndex=0, nwkAddrAssocDevList=[]]
16:15:17.974 [DEBUG] [.transaction.ZigBeeTransactionManager] - notifyTransactionCommand: IeeeAddressResponse [EDD1/0 -> 0000/0, cluster=8001, TID=00, status=SUCCESS, ieeeAddrRemoteDev=7CB03EAA0A00FD9A, nwkAddrRemoteDev=EDD1, startIndex=0, nwkAddrAssocDevList=[]]
16:15:17.974 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-- ZToolPacket (FE 14 45 FF D1 ED 00 01 80 00 05 00 00 00 9A FD 00 0A AA 3E B0 7C D1 ED 1F)
16:15:17.974 [DEBUG] [531.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=20, apiId=45 FF, data=FE 14 45 FF D1 ED 00 01 80 00 05 00 00 00 9A FD 00 0A AA 3E B0 7C D1 ED 1F, checksum=1F, error=false
16:15:17.974 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=EDD1/0, destinationAddress=0000/0, profile=0000, cluster=8001, addressMode=null, radius=0, apsSecurity=false, ackRequest=false, apsCounter=--, rssi=--, lqi=--, payload=00 00 9A FD 00 0A AA 3E B0 7C D1 ED]
16:15:17.975 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX CMD: IeeeAddressResponse [EDD1/0 -> 0000/0, cluster=8001, TID=00, status=SUCCESS, ieeeAddrRemoteDev=7CB03EAA0A00FD9A, nwkAddrRemoteDev=EDD1, startIndex=null, nwkAddrAssocDevList=[]]
16:15:17.975 [DEBUG] [.transaction.ZigBeeTransactionManager] - notifyTransactionCommand: IeeeAddressResponse [EDD1/0 -> 0000/0, cluster=8001, TID=00, status=SUCCESS, ieeeAddrRemoteDev=7CB03EAA0A00FD9A, nwkAddrRemoteDev=EDD1, startIndex=null, nwkAddrAssocDevList=[]]
16:15:18.158 [DEBUG] [database.ZigBeeNetworkDatabaseManager] - 7CB03EAA0A00FD9A: Data store: Writing node.
16:15:18.161 [DEBUG] [nding.zigbee.internal.ZigBeeDataStore] - 7CB03EAA0A00FD9A: ZigBee saving network state complete.
16:15:18.628 [DEBUG] [discovery.ZigBeeNodeServiceDiscoverer] - 7CB03EAA0A00FD9A: Node SVC Discovery: running NWK_ADDRESS
16:15:18.629 [DEBUG] [.transaction.ZigBeeTransactionManager] - 7CB03EAA0A00FD9A: Creating new Transaction Queue
16:15:18.629 [DEBUG] [ee.transaction.ZigBeeTransactionQueue] - 7CB03EAA0A00FD9A: Set profile to ZigBeeTransactionProfile [maxOutstandingTransactions=1, interTransactionDelay=50, maxRetries=2]
16:15:18.629 [DEBUG] [ee.transaction.ZigBeeTransactionQueue] - 7CB03EAA0A00FD9A: Added transaction to queue, len=1, transaction=ZigBeeTransaction [ieeeAddress=7CB03EAA0A00FD9A queueTime=0, state=WAITING, sendCnt=0, command=NetworkAddressRequest [0000/0 -> EDD1/0, cluster=0000, TID=--, ieeeAddr=7CB03EAA0A00FD9A, requestType=0, startIndex=0]]
16:15:18.629 [DEBUG] [.transaction.ZigBeeTransactionManager] - Transaction Manager: Send Next transaction. outstandingTransactions=1, outstandingQueues=1, sleepy=0/3
16:15:18.630 [DEBUG] [.transaction.ZigBeeTransactionManager] - EDD1/0: Sending ZigBeeTransaction [ieeeAddress=7CB03EAA0A00FD9A queueTime=0, state=WAITING, sendCnt=0, command=NetworkAddressRequest [0000/0 -> EDD1/0, cluster=0000, TID=06, ieeeAddr=7CB03EAA0A00FD9A, requestType=0, startIndex=0]]
16:15:18.630 [DEBUG] [.transaction.ZigBeeTransactionManager] - transactionListenerAdded: 2 outstanding
16:15:18.630 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX CMD: NetworkAddressRequest [0000/0 -> EDD1/0, cluster=0000, TID=06, ieeeAddr=7CB03EAA0A00FD9A, requestType=0, startIndex=0]
16:15:18.630 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0000/0, destinationAddress=EDD1/0, profile=0000, cluster=0000, addressMode=DEVICE, radius=8, apsSecurity=false, ackRequest=true, apsCounter=07, rssi=--, lqi=--, payload=06 9A FD 00 0A AA 3E B0 7C 00 00]
16:15:18.630 [DEBUG] [531.network.impl.CommandInterfaceImpl] - ->  AF_DATA_REQUEST (Packet: subsystem=null, length=21, apiId=24 01, data=FE 15 24 01 D1 ED 00 00 00 00 07 30 08 0B 06 9A FD 00 0A AA 3E B0 7C 00 00 0B, checksum=0B, error=false)
16:15:18.643 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-  AF_DATA_SRSP (FE 01 64 01 00 64)
16:15:18.643 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX STA: msgTag=06 state=TX_ACK
16:15:18.643 [DEBUG] [.transaction.ZigBeeTransactionManager] - notifyTransactionProgress: TID=06, state=TX_ACK, outstanding=2
16:15:18.644 [DEBUG] [.zigbee.transaction.ZigBeeTransaction] - Transaction state changed: nwk=EDD1, TID=06, event=TX_ACK, state=TRANSMITTED
16:15:18.706 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-- ZToolPacket (FE 0D 45 80 00 9A FD 00 0A AA 3E B0 7C D1 ED 03 00 C2)
16:15:18.707 [DEBUG] [531.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=13, apiId=45 80, data=FE 0D 45 80 00 9A FD 00 0A AA 3E B0 7C D1 ED 03 00 C2, checksum=C2, error=false
16:15:18.707 [DEBUG] [ee.dongle.cc2531.ZigBeeDongleTiCc2531] - Unhandled ZToolPacket type 0x4580
16:15:18.708 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-- ZToolPacket (FE 14 45 FF D1 ED 00 00 80 00 06 00 00 00 9A FD 00 0A AA 3E B0 7C D1 ED 1D)
16:15:18.709 [DEBUG] [531.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=20, apiId=45 FF, data=FE 14 45 FF D1 ED 00 00 80 00 06 00 00 00 9A FD 00 0A AA 3E B0 7C D1 ED 1D, checksum=1D, error=false
16:15:18.709 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=EDD1/0, destinationAddress=0000/0, profile=0000, cluster=8000, addressMode=null, radius=0, apsSecurity=false, ackRequest=false, apsCounter=--, rssi=--, lqi=--, payload=00 00 9A FD 00 0A AA 3E B0 7C D1 ED]
16:15:18.711 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX CMD: NetworkAddressResponse [EDD1/0 -> 0000/0, cluster=8000, TID=00, status=SUCCESS, ieeeAddrRemoteDev=7CB03EAA0A00FD9A, nwkAddrRemoteDev=EDD1, startIndex=null, nwkAddrAssocDevList=[]]
16:15:18.712 [DEBUG] [.transaction.ZigBeeTransactionManager] - notifyTransactionCommand: NetworkAddressResponse [EDD1/0 -> 0000/0, cluster=8000, TID=00, status=SUCCESS, ieeeAddrRemoteDev=7CB03EAA0A00FD9A, nwkAddrRemoteDev=EDD1, startIndex=null, nwkAddrAssocDevList=[]]
16:15:18.713 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-- ZB_FIND_DEVICE_CONFIRM (FE 0B 46 85 01 D1 ED 9A FD 00 0A AA 3E B0 7C C0)
16:15:18.713 [DEBUG] [531.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=11, apiId=46 85, data=FE 0B 46 85 01 D1 ED 9A FD 00 0A AA 3E B0 7C C0, checksum=C0, error=false
16:15:18.714 [DEBUG] [ee.dongle.cc2531.ZigBeeDongleTiCc2531] - Unhandled ZToolPacket type 0x4685
16:15:25.901 [DEBUG] [.transaction.ZigBeeTransactionManager] - Transaction complete: ZigBeeTransaction [ieeeAddress=null queueTime=8031, state=FAILED, sendCnt=1, command=IeeeAddressRequest [0000/0 -> EDD1/0, cluster=0001, TID=05, nwkAddrOfInterest=EDD1, requestType=0, startIndex=0]]
16:15:25.902 [DEBUG] [.transaction.ZigBeeTransactionManager] - transactionListenerRemoved: 1 outstanding
16:15:25.903 [DEBUG] [ee.transaction.ZigBeeTransactionQueue] - 7CB03EAA0A00FD9A: transactionComplete but not outstanding, state=FAILED, outstanding=1
16:15:25.903 [DEBUG] [.zigbee.transaction.ZigBeeTransaction] - Transaction cancelled: ZigBeeTransaction [ieeeAddress=null queueTime=8033, state=CANCELLED, sendCnt=1, command=IeeeAddressRequest [0000/0 -> EDD1/0, cluster=0001, TID=05, nwkAddrOfInterest=EDD1, requestType=0, startIndex=0]]
16:15:25.904 [DEBUG] [.transaction.ZigBeeTransactionManager] - Transaction complete: ZigBeeTransaction [ieeeAddress=null queueTime=8034, state=CANCELLED, sendCnt=1, command=IeeeAddressRequest [0000/0 -> EDD1/0, cluster=0001, TID=05, nwkAddrOfInterest=EDD1, requestType=0, startIndex=0]]
16:15:25.905 [DEBUG] [.transaction.ZigBeeTransactionManager] - transactionListenerRemoved: 1 outstanding
16:15:25.906 [DEBUG] [ee.transaction.ZigBeeTransactionQueue] - 7CB03EAA0A00FD9A: transactionComplete but not outstanding, state=CANCELLED, outstanding=1
16:15:25.906 [DEBUG] [.transaction.ZigBeeTransactionManager] - Transaction Manager: Send Next transaction. outstandingTransactions=1, outstandingQueues=0, sleepy=0/3
16:15:25.907 [DEBUG] [.transaction.ZigBeeTransactionManager] - Transaction Manager: Send Next transaction. outstandingTransactions=1, outstandingQueues=0, sleepy=0/3
16:15:26.649 [DEBUG] [.transaction.ZigBeeTransactionManager] - Transaction complete: ZigBeeTransaction [ieeeAddress=7CB03EAA0A00FD9A queueTime=8020, state=FAILED, sendCnt=1, command=NetworkAddressRequest [0000/0 -> EDD1/0, cluster=0000, TID=06, ieeeAddr=7CB03EAA0A00FD9A, requestType=0, startIndex=0]]
16:15:26.649 [DEBUG] [.transaction.ZigBeeTransactionManager] - transactionListenerRemoved: 0 outstanding
16:15:26.649 [DEBUG] [ee.transaction.ZigBeeTransactionQueue] - 7CB03EAA0A00FD9A: transactionComplete, state=FAILED, outstanding=0
16:15:26.649 [DEBUG] [ee.transaction.ZigBeeTransactionQueue] - 7CB03EAA0A00FD9A: Added transaction to queue, len=1, transaction=ZigBeeTransaction [ieeeAddress=7CB03EAA0A00FD9A queueTime=8020, state=WAITING, sendCnt=1, command=NetworkAddressRequest [0000/0 -> EDD1/0, cluster=0000, TID=06, ieeeAddr=7CB03EAA0A00FD9A, requestType=0, startIndex=0]]
16:15:26.650 [DEBUG] [.transaction.ZigBeeTransactionManager] - Transaction Manager: Send Next transaction. outstandingTransactions=0, outstandingQueues=1, sleepy=0/3
16:15:26.650 [DEBUG] [.transaction.ZigBeeTransactionManager] - EDD1/0: Sending ZigBeeTransaction [ieeeAddress=7CB03EAA0A00FD9A queueTime=8021, state=WAITING, sendCnt=1, command=NetworkAddressRequest [0000/0 -> EDD1/0, cluster=0000, TID=06, ieeeAddr=7CB03EAA0A00FD9A, requestType=0, startIndex=0]]
16:15:26.650 [DEBUG] [.transaction.ZigBeeTransactionManager] - transactionListenerAdded: 1 outstanding
16:15:26.650 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX CMD: NetworkAddressRequest [0000/0 -> EDD1/0, cluster=0000, TID=06, ieeeAddr=7CB03EAA0A00FD9A, requestType=0, startIndex=0]
16:15:26.650 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0000/0, destinationAddress=EDD1/0, profile=0000, cluster=0000, addressMode=DEVICE, radius=8, apsSecurity=false, ackRequest=true, apsCounter=08, rssi=--, lqi=--, payload=06 9A FD 00 0A AA 3E B0 7C 00 00]
16:15:26.651 [DEBUG] [531.network.impl.CommandInterfaceImpl] - ->  AF_DATA_REQUEST (Packet: subsystem=null, length=21, apiId=24 01, data=FE 15 24 01 D1 ED 00 00 00 00 08 30 08 0B 06 9A FD 00 0A AA 3E B0 7C 00 00 04, checksum=04, error=false)
16:15:26.664 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-  AF_DATA_SRSP (FE 01 64 01 00 64)
16:15:26.664 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX STA: msgTag=06 state=TX_ACK
16:15:26.664 [DEBUG] [.transaction.ZigBeeTransactionManager] - notifyTransactionProgress: TID=06, state=TX_ACK, outstanding=1
16:15:26.665 [DEBUG] [.zigbee.transaction.ZigBeeTransaction] - Transaction state changed: nwk=EDD1, TID=06, event=TX_ACK, state=TRANSMITTED
16:15:26.741 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-- ZToolPacket (FE 0D 45 80 00 9A FD 00 0A AA 3E B0 7C D1 ED 00 00 C1)
16:15:26.742 [DEBUG] [531.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=13, apiId=45 80, data=FE 0D 45 80 00 9A FD 00 0A AA 3E B0 7C D1 ED 00 00 C1, checksum=C1, error=false
16:15:26.743 [DEBUG] [ee.dongle.cc2531.ZigBeeDongleTiCc2531] - Unhandled ZToolPacket type 0x4580
16:15:26.744 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-- ZToolPacket (FE 14 45 FF D1 ED 00 00 80 00 06 00 00 00 9A FD 00 0A AA 3E B0 7C D1 ED 1D)
16:15:26.744 [DEBUG] [531.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=20, apiId=45 FF, data=FE 14 45 FF D1 ED 00 00 80 00 06 00 00 00 9A FD 00 0A AA 3E B0 7C D1 ED 1D, checksum=1D, error=false
16:15:26.745 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=EDD1/0, destinationAddress=0000/0, profile=0000, cluster=8000, addressMode=null, radius=0, apsSecurity=false, ackRequest=false, apsCounter=--, rssi=--, lqi=--, payload=00 00 9A FD 00 0A AA 3E B0 7C D1 ED]
16:15:26.746 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX CMD: NetworkAddressResponse [EDD1/0 -> 0000/0, cluster=8000, TID=00, status=SUCCESS, ieeeAddrRemoteDev=7CB03EAA0A00FD9A, nwkAddrRemoteDev=EDD1, startIndex=null, nwkAddrAssocDevList=[]]
16:15:26.746 [DEBUG] [.transaction.ZigBeeTransactionManager] - notifyTransactionCommand: NetworkAddressResponse [EDD1/0 -> 0000/0, cluster=8000, TID=00, status=SUCCESS, ieeeAddrRemoteDev=7CB03EAA0A00FD9A, nwkAddrRemoteDev=EDD1, startIndex=null, nwkAddrAssocDevList=[]]
16:15:26.746 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-- ZB_FIND_DEVICE_CONFIRM (FE 0B 46 85 01 D1 ED 9A FD 00 0A AA 3E B0 7C C0)
16:15:26.746 [DEBUG] [531.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=11, apiId=46 85, data=FE 0B 46 85 01 D1 ED 9A FD 00 0A AA 3E B0 7C C0, checksum=C0, error=false
16:15:26.747 [DEBUG] [ee.dongle.cc2531.ZigBeeDongleTiCc2531] - Unhandled ZToolPacket type 0x4685
16:15:34.675 [DEBUG] [.transaction.ZigBeeTransactionManager] - Transaction complete: ZigBeeTransaction [ieeeAddress=7CB03EAA0A00FD9A queueTime=16046, state=FAILED, sendCnt=2, command=NetworkAddressRequest [0000/0 -> EDD1/0, cluster=0000, TID=06, ieeeAddr=7CB03EAA0A00FD9A, requestType=0, startIndex=0]]
16:15:34.675 [DEBUG] [.transaction.ZigBeeTransactionManager] - transactionListenerRemoved: 0 outstanding
16:15:34.675 [DEBUG] [ee.transaction.ZigBeeTransactionQueue] - 7CB03EAA0A00FD9A: transactionComplete, state=FAILED, outstanding=0
16:15:34.676 [DEBUG] [ee.transaction.ZigBeeTransactionQueue] - 7CB03EAA0A00FD9A: transactionComplete exceeded max retries 2
16:15:34.676 [DEBUG] [.zigbee.transaction.ZigBeeTransaction] - Transaction cancelled: ZigBeeTransaction [ieeeAddress=7CB03EAA0A00FD9A queueTime=16047, state=CANCELLED, sendCnt=2, command=NetworkAddressRequest [0000/0 -> EDD1/0, cluster=0000, TID=06, ieeeAddr=7CB03EAA0A00FD9A, requestType=0, startIndex=0]]
16:15:34.676 [DEBUG] [.transaction.ZigBeeTransactionManager] - Transaction complete: ZigBeeTransaction [ieeeAddress=7CB03EAA0A00FD9A queueTime=16047, state=CANCELLED, sendCnt=2, command=NetworkAddressRequest [0000/0 -> EDD1/0, cluster=0000, TID=06, ieeeAddr=7CB03EAA0A00FD9A, requestType=0, startIndex=0]]
16:15:34.676 [DEBUG] [discovery.ZigBeeNodeServiceDiscoverer] - 7CB03EAA0A00FD9A: Node SVC Discovery: NetworkAddressRequest returned null
16:15:34.676 [DEBUG] [.transaction.ZigBeeTransactionManager] - transactionListenerRemoved: 0 outstanding
16:15:34.677 [DEBUG] [ee.transaction.ZigBeeTransactionQueue] - 7CB03EAA0A00FD9A: transactionComplete but not outstanding, state=CANCELLED, outstanding=0
16:15:34.677 [DEBUG] [.transaction.ZigBeeTransactionManager] - Transaction Manager: Send Next transaction. outstandingTransactions=0, outstandingQueues=0, sleepy=0/3
16:15:34.677 [DEBUG] [.transaction.ZigBeeTransactionManager] - Transaction Manager: Send Next transaction. outstandingTransactions=0, outstandingQueues=0, sleepy=0/3
16:15:34.677 [DEBUG] [ee.transaction.ZigBeeTransactionQueue] - Broadcast: Added transaction to queue, len=1, transaction=ZigBeeTransaction [ieeeAddress=null queueTime=0, state=WAITING, sendCnt=0, command=NetworkAddressRequest [0000/0 -> FFFF/0, cluster=0000, TID=--, ieeeAddr=7CB03EAA0A00FD9A, requestType=0, startIndex=0]]
16:15:34.677 [DEBUG] [.transaction.ZigBeeTransactionManager] - Transaction Manager: Send Next transaction. outstandingTransactions=0, outstandingQueues=1, sleepy=0/3
16:15:34.678 [DEBUG] [.transaction.ZigBeeTransactionManager] - FFFF/0: Sending ZigBeeTransaction [ieeeAddress=null queueTime=1, state=WAITING, sendCnt=0, command=NetworkAddressRequest [0000/0 -> FFFF/0, cluster=0000, TID=07, ieeeAddr=7CB03EAA0A00FD9A, requestType=0, startIndex=0]]
16:15:34.678 [DEBUG] [.transaction.ZigBeeTransactionManager] - transactionListenerAdded: 1 outstanding
16:15:34.678 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX CMD: NetworkAddressRequest [0000/0 -> FFFF/0, cluster=0000, TID=07, ieeeAddr=7CB03EAA0A00FD9A, requestType=0, startIndex=0]
16:15:34.678 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0000/0, destinationAddress=FFFF/0, profile=0000, cluster=0000, addressMode=DEVICE, radius=8, apsSecurity=false, ackRequest=true, apsCounter=09, rssi=--, lqi=--, payload=07 9A FD 00 0A AA 3E B0 7C 00 00]
16:15:34.678 [DEBUG] [531.network.impl.CommandInterfaceImpl] - ->  AF_DATA_REQUEST (Packet: subsystem=null, length=21, apiId=24 01, data=FE 15 24 01 FF FF 00 00 00 00 09 30 08 0B 07 9A FD 00 0A AA 3E B0 7C 00 00 38, checksum=38, error=false)
16:15:34.706 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-  AF_DATA_SRSP (FE 01 64 01 00 64)
16:15:34.706 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX STA: msgTag=07 state=TX_ACK
16:15:34.706 [DEBUG] [.transaction.ZigBeeTransactionManager] - notifyTransactionProgress: TID=07, state=TX_ACK, outstanding=1
16:15:34.707 [DEBUG] [.zigbee.transaction.ZigBeeTransaction] - Transaction state changed: nwk=FFFF, TID=07, event=TX_ACK, state=TRANSMITTED
16:15:34.754 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-- ZToolPacket (FE 0D 45 80 00 9A FD 00 0A AA 3E B0 7C D1 ED 00 00 C1)
16:15:34.754 [DEBUG] [531.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=13, apiId=45 80, data=FE 0D 45 80 00 9A FD 00 0A AA 3E B0 7C D1 ED 00 00 C1, checksum=C1, error=false
16:15:34.755 [DEBUG] [ee.dongle.cc2531.ZigBeeDongleTiCc2531] - Unhandled ZToolPacket type 0x4580
16:15:34.756 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-- ZToolPacket (FE 14 45 FF D1 ED 00 00 80 00 07 00 00 00 9A FD 00 0A AA 3E B0 7C D1 ED 1C)
16:15:34.756 [DEBUG] [531.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=20, apiId=45 FF, data=FE 14 45 FF D1 ED 00 00 80 00 07 00 00 00 9A FD 00 0A AA 3E B0 7C D1 ED 1C, checksum=1C, error=false
16:15:34.757 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=EDD1/0, destinationAddress=0000/0, profile=0000, cluster=8000, addressMode=null, radius=0, apsSecurity=false, ackRequest=false, apsCounter=--, rssi=--, lqi=--, payload=00 00 9A FD 00 0A AA 3E B0 7C D1 ED]
16:15:34.758 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX CMD: NetworkAddressResponse [EDD1/0 -> 0000/0, cluster=8000, TID=00, status=SUCCESS, ieeeAddrRemoteDev=7CB03EAA0A00FD9A, nwkAddrRemoteDev=EDD1, startIndex=null, nwkAddrAssocDevList=[]]
16:15:34.759 [DEBUG] [.transaction.ZigBeeTransactionManager] - notifyTransactionCommand: NetworkAddressResponse [EDD1/0 -> 0000/0, cluster=8000, TID=00, status=SUCCESS, ieeeAddrRemoteDev=7CB03EAA0A00FD9A, nwkAddrRemoteDev=EDD1, startIndex=null, nwkAddrAssocDevList=[]]
16:15:34.759 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-- ZB_FIND_DEVICE_CONFIRM (FE 0B 46 85 01 D1 ED 9A FD 00 0A AA 3E B0 7C C0)
16:15:34.760 [DEBUG] [531.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=11, apiId=46 85, data=FE 0B 46 85 01 D1 ED 9A FD 00 0A AA 3E B0 7C C0, checksum=C0, error=false
16:15:34.760 [DEBUG] [ee.dongle.cc2531.ZigBeeDongleTiCc2531] - Unhandled ZToolPacket type 0x4685
16:15:42.711 [DEBUG] [.transaction.ZigBeeTransactionManager] - Transaction complete: ZigBeeTransaction [ieeeAddress=null queueTime=8034, state=FAILED, sendCnt=1, command=NetworkAddressRequest [0000/0 -> FFFF/0, cluster=0000, TID=07, ieeeAddr=7CB03EAA0A00FD9A, requestType=0, startIndex=0]]
16:15:42.711 [DEBUG] [.transaction.ZigBeeTransactionManager] - transactionListenerRemoved: 0 outstanding
16:15:42.711 [DEBUG] [ee.transaction.ZigBeeTransactionQueue] - Broadcast: transactionComplete, state=FAILED, outstanding=0
16:15:42.711 [DEBUG] [ee.transaction.ZigBeeTransactionQueue] - Broadcast: transactionComplete exceeded max retries 1
16:15:42.711 [DEBUG] [.zigbee.transaction.ZigBeeTransaction] - Transaction cancelled: ZigBeeTransaction [ieeeAddress=null queueTime=8034, state=CANCELLED, sendCnt=1, command=NetworkAddressRequest [0000/0 -> FFFF/0, cluster=0000, TID=07, ieeeAddr=7CB03EAA0A00FD9A, requestType=0, startIndex=0]]
16:15:42.712 [DEBUG] [.transaction.ZigBeeTransactionManager] - Transaction complete: ZigBeeTransaction [ieeeAddress=null queueTime=8034, state=CANCELLED, sendCnt=1, command=NetworkAddressRequest [0000/0 -> FFFF/0, cluster=0000, TID=07, ieeeAddr=7CB03EAA0A00FD9A, requestType=0, startIndex=0]]
16:15:42.712 [DEBUG] [discovery.ZigBeeNodeServiceDiscoverer] - 7CB03EAA0A00FD9A: Node SVC Discovery: NetworkAddressRequest returned null
16:15:42.712 [DEBUG] [.transaction.ZigBeeTransactionManager] - transactionListenerRemoved: 0 outstanding
16:15:42.712 [DEBUG] [ee.transaction.ZigBeeTransactionQueue] - Broadcast: transactionComplete but not outstanding, state=CANCELLED, outstanding=0
16:15:42.712 [DEBUG] [discovery.ZigBeeNodeServiceDiscoverer] - 7CB03EAA0A00FD9A: Node SVC Discovery: NetworkAddressRequest failed after unicast and broadcast
16:15:42.712 [DEBUG] [.transaction.ZigBeeTransactionManager] - Transaction Manager: Send Next transaction. outstandingTransactions=0, outstandingQueues=0, sleepy=0/3
16:15:42.712 [DEBUG] [.transaction.ZigBeeTransactionManager] - Transaction Manager: Send Next transaction. outstandingTransactions=0, outstandingQueues=0, sleepy=0/3
16:15:42.712 [DEBUG] [discovery.ZigBeeNodeServiceDiscoverer] - 7CB03EAA0A00FD9A: Node SVC Discovery: request NWK_ADDRESS failed. Retry 1, wait 2328ms before retry.
16:15:45.046 [DEBUG] [discovery.ZigBeeNodeServiceDiscoverer] - 7CB03EAA0A00FD9A: Node SVC Discovery: running NWK_ADDRESS
16:15:45.046 [DEBUG] [ee.transaction.ZigBeeTransactionQueue] - 7CB03EAA0A00FD9A: Added transaction to queue, len=1, transaction=ZigBeeTransaction [ieeeAddress=7CB03EAA0A00FD9A queueTime=0, state=WAITING, sendCnt=0, command=NetworkAddressRequest [0000/0 -> EDD1/0, cluster=0000, TID=--, ieeeAddr=7CB03EAA0A00FD9A, requestType=0, startIndex=0]]
16:15:45.046 [DEBUG] [.transaction.ZigBeeTransactionManager] - Transaction Manager: Send Next transaction. outstandingTransactions=0, outstandingQueues=1, sleepy=0/3
16:15:45.047 [DEBUG] [.transaction.ZigBeeTransactionManager] - EDD1/0: Sending ZigBeeTransaction [ieeeAddress=7CB03EAA0A00FD9A queueTime=1, state=WAITING, sendCnt=0, command=NetworkAddressRequest [0000/0 -> EDD1/0, cluster=0000, TID=08, ieeeAddr=7CB03EAA0A00FD9A, requestType=0, startIndex=0]]
16:15:45.047 [DEBUG] [.transaction.ZigBeeTransactionManager] - transactionListenerAdded: 1 outstanding
16:15:45.047 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX CMD: NetworkAddressRequest [0000/0 -> EDD1/0, cluster=0000, TID=08, ieeeAddr=7CB03EAA0A00FD9A, requestType=0, startIndex=0]
16:15:45.047 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0000/0, destinationAddress=EDD1/0, profile=0000, cluster=0000, addressMode=DEVICE, radius=8, apsSecurity=false, ackRequest=true, apsCounter=0A, rssi=--, lqi=--, payload=08 9A FD 00 0A AA 3E B0 7C 00 00]
16:15:45.048 [DEBUG] [531.network.impl.CommandInterfaceImpl] - ->  AF_DATA_REQUEST (Packet: subsystem=null, length=21, apiId=24 01, data=FE 15 24 01 D1 ED 00 00 00 00 0A 30 08 0B 08 9A FD 00 0A AA 3E B0 7C 00 00 08, checksum=08, error=false)
16:15:45.061 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-  AF_DATA_SRSP (FE 01 64 01 00 64)
16:15:45.061 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX STA: msgTag=08 state=TX_ACK
16:15:45.061 [DEBUG] [.transaction.ZigBeeTransactionManager] - notifyTransactionProgress: TID=08, state=TX_ACK, outstanding=1
16:15:45.062 [DEBUG] [.zigbee.transaction.ZigBeeTransaction] - Transaction state changed: nwk=EDD1, TID=08, event=TX_ACK, state=TRANSMITTED
16:15:45.124 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-- ZToolPacket (FE 0D 45 80 00 9A FD 00 0A AA 3E B0 7C D1 ED 02 00 C3)
16:15:45.124 [DEBUG] [531.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=13, apiId=45 80, data=FE 0D 45 80 00 9A FD 00 0A AA 3E B0 7C D1 ED 02 00 C3, checksum=C3, error=false
16:15:45.124 [DEBUG] [ee.dongle.cc2531.ZigBeeDongleTiCc2531] - Unhandled ZToolPacket type 0x4580
16:15:45.124 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-- ZToolPacket (FE 14 45 FF D1 ED 00 00 80 00 08 00 00 00 9A FD 00 0A AA 3E B0 7C D1 ED 13)
16:15:45.124 [DEBUG] [531.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=20, apiId=45 FF, data=FE 14 45 FF D1 ED 00 00 80 00 08 00 00 00 9A FD 00 0A AA 3E B0 7C D1 ED 13, checksum=13, error=false
16:15:45.125 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=EDD1/0, destinationAddress=0000/0, profile=0000, cluster=8000, addressMode=null, radius=0, apsSecurity=false, ackRequest=false, apsCounter=--, rssi=--, lqi=--, payload=00 00 9A FD 00 0A AA 3E B0 7C D1 ED]
16:15:45.125 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX CMD: NetworkAddressResponse [EDD1/0 -> 0000/0, cluster=8000, TID=00, status=SUCCESS, ieeeAddrRemoteDev=7CB03EAA0A00FD9A, nwkAddrRemoteDev=EDD1, startIndex=null, nwkAddrAssocDevList=[]]
16:15:45.125 [DEBUG] [.transaction.ZigBeeTransactionManager] - notifyTransactionCommand: NetworkAddressResponse [EDD1/0 -> 0000/0, cluster=8000, TID=00, status=SUCCESS, ieeeAddrRemoteDev=7CB03EAA0A00FD9A, nwkAddrRemoteDev=EDD1, startIndex=null, nwkAddrAssocDevList=[]]
16:15:45.125 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-- ZB_FIND_DEVICE_CONFIRM (FE 0B 46 85 01 D1 ED 9A FD 00 0A AA 3E B0 7C C0)
16:15:45.125 [DEBUG] [531.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=11, apiId=46 85, data=FE 0B 46 85 01 D1 ED 9A FD 00 0A AA 3E B0 7C C0, checksum=C0, error=false
16:15:45.125 [DEBUG] [ee.dongle.cc2531.ZigBeeDongleTiCc2531] - Unhandled ZToolPacket type 0x4685
16:15:53.069 [DEBUG] [.transaction.ZigBeeTransactionManager] - Transaction complete: ZigBeeTransaction [ieeeAddress=7CB03EAA0A00FD9A queueTime=8023, state=FAILED, sendCnt=1, command=NetworkAddressRequest [0000/0 -> EDD1/0, cluster=0000, TID=08, ieeeAddr=7CB03EAA0A00FD9A, requestType=0, startIndex=0]]
16:15:53.069 [DEBUG] [.transaction.ZigBeeTransactionManager] - transactionListenerRemoved: 0 outstanding
16:15:53.069 [DEBUG] [ee.transaction.ZigBeeTransactionQueue] - 7CB03EAA0A00FD9A: transactionComplete, state=FAILED, outstanding=0
16:15:53.069 [DEBUG] [ee.transaction.ZigBeeTransactionQueue] - 7CB03EAA0A00FD9A: Added transaction to queue, len=1, transaction=ZigBeeTransaction [ieeeAddress=7CB03EAA0A00FD9A queueTime=8023, state=WAITING, sendCnt=1, command=NetworkAddressRequest [0000/0 -> EDD1/0, cluster=0000, TID=08, ieeeAddr=7CB03EAA0A00FD9A, requestType=0, startIndex=0]]
16:15:53.070 [DEBUG] [.transaction.ZigBeeTransactionManager] - Transaction Manager: Send Next transaction. outstandingTransactions=0, outstandingQueues=1, sleepy=0/3
16:15:53.070 [DEBUG] [.transaction.ZigBeeTransactionManager] - EDD1/0: Sending ZigBeeTransaction [ieeeAddress=7CB03EAA0A00FD9A queueTime=8024, state=WAITING, sendCnt=1, command=NetworkAddressRequest [0000/0 -> EDD1/0, cluster=0000, TID=08, ieeeAddr=7CB03EAA0A00FD9A, requestType=0, startIndex=0]]
16:15:53.070 [DEBUG] [.transaction.ZigBeeTransactionManager] - transactionListenerAdded: 1 outstanding
16:15:53.070 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX CMD: NetworkAddressRequest [0000/0 -> EDD1/0, cluster=0000, TID=08, ieeeAddr=7CB03EAA0A00FD9A, requestType=0, startIndex=0]
16:15:53.071 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0000/0, destinationAddress=EDD1/0, profile=0000, cluster=0000, addressMode=DEVICE, radius=8, apsSecurity=false, ackRequest=true, apsCounter=0B, rssi=--, lqi=--, payload=08 9A FD 00 0A AA 3E B0 7C 00 00]
16:15:53.071 [DEBUG] [531.network.impl.CommandInterfaceImpl] - ->  AF_DATA_REQUEST (Packet: subsystem=null, length=21, apiId=24 01, data=FE 15 24 01 D1 ED 00 00 00 00 0B 30 08 0B 08 9A FD 00 0A AA 3E B0 7C 00 00 09, checksum=09, error=false)
16:15:53.085 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-  AF_DATA_SRSP (FE 01 64 01 00 64)
16:15:53.085 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX STA: msgTag=08 state=TX_ACK
16:15:53.085 [DEBUG] [.transaction.ZigBeeTransactionManager] - notifyTransactionProgress: TID=08, state=TX_ACK, outstanding=1
16:15:53.086 [DEBUG] [.zigbee.transaction.ZigBeeTransaction] - Transaction state changed: nwk=EDD1, TID=08, event=TX_ACK, state=TRANSMITTED
16:15:53.148 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-- ZToolPacket (FE 0D 45 80 00 9A FD 00 0A AA 3E B0 7C D1 ED 00 00 C1)
16:15:53.148 [DEBUG] [531.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=13, apiId=45 80, data=FE 0D 45 80 00 9A FD 00 0A AA 3E B0 7C D1 ED 00 00 C1, checksum=C1, error=false
16:15:53.148 [DEBUG] [ee.dongle.cc2531.ZigBeeDongleTiCc2531] - Unhandled ZToolPacket type 0x4580
16:15:53.148 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-- ZToolPacket (FE 14 45 FF D1 ED 00 00 80 00 08 00 00 00 9A FD 00 0A AA 3E B0 7C D1 ED 13)
16:15:53.148 [DEBUG] [531.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=20, apiId=45 FF, data=FE 14 45 FF D1 ED 00 00 80 00 08 00 00 00 9A FD 00 0A AA 3E B0 7C D1 ED 13, checksum=13, error=false
16:15:53.149 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=EDD1/0, destinationAddress=0000/0, profile=0000, cluster=8000, addressMode=null, radius=0, apsSecurity=false, ackRequest=false, apsCounter=--, rssi=--, lqi=--, payload=00 00 9A FD 00 0A AA 3E B0 7C D1 ED]
16:15:53.149 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX CMD: NetworkAddressResponse [EDD1/0 -> 0000/0, cluster=8000, TID=00, status=SUCCESS, ieeeAddrRemoteDev=7CB03EAA0A00FD9A, nwkAddrRemoteDev=EDD1, startIndex=null, nwkAddrAssocDevList=[]]
16:15:53.149 [DEBUG] [.transaction.ZigBeeTransactionManager] - notifyTransactionCommand: NetworkAddressResponse [EDD1/0 -> 0000/0, cluster=8000, TID=00, status=SUCCESS, ieeeAddrRemoteDev=7CB03EAA0A00FD9A, nwkAddrRemoteDev=EDD1, startIndex=null, nwkAddrAssocDevList=[]]
16:15:53.164 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-- ZB_FIND_DEVICE_CONFIRM (FE 0B 46 85 01 D1 ED 9A FD 00 0A AA 3E B0 7C C0)
16:15:53.164 [DEBUG] [531.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=11, apiId=46 85, data=FE 0B 46 85 01 D1 ED 9A FD 00 0A AA 3E B0 7C C0, checksum=C0, error=false
16:15:53.164 [DEBUG] [ee.dongle.cc2531.ZigBeeDongleTiCc2531] - Unhandled ZToolPacket type 0x4685
16:16:01.095 [DEBUG] [.transaction.ZigBeeTransactionManager] - Transaction complete: ZigBeeTransaction [ieeeAddress=7CB03EAA0A00FD9A queueTime=16049, state=FAILED, sendCnt=2, command=NetworkAddressRequest [0000/0 -> EDD1/0, cluster=0000, TID=08, ieeeAddr=7CB03EAA0A00FD9A, requestType=0, startIndex=0]]
16:16:01.095 [DEBUG] [.transaction.ZigBeeTransactionManager] - transactionListenerRemoved: 0 outstanding
16:16:01.096 [DEBUG] [ee.transaction.ZigBeeTransactionQueue] - 7CB03EAA0A00FD9A: transactionComplete, state=FAILED, outstanding=0
16:16:01.096 [DEBUG] [ee.transaction.ZigBeeTransactionQueue] - 7CB03EAA0A00FD9A: transactionComplete exceeded max retries 2
16:16:01.096 [DEBUG] [.zigbee.transaction.ZigBeeTransaction] - Transaction cancelled: ZigBeeTransaction [ieeeAddress=7CB03EAA0A00FD9A queueTime=16050, state=CANCELLED, sendCnt=2, command=NetworkAddressRequest [0000/0 -> EDD1/0, cluster=0000, TID=08, ieeeAddr=7CB03EAA0A00FD9A, requestType=0, startIndex=0]]
16:16:01.096 [DEBUG] [discovery.ZigBeeNodeServiceDiscoverer] - 7CB03EAA0A00FD9A: Node SVC Discovery: NetworkAddressRequest returned null
16:16:01.096 [DEBUG] [.transaction.ZigBeeTransactionManager] - Transaction complete: ZigBeeTransaction [ieeeAddress=7CB03EAA0A00FD9A queueTime=16050, state=CANCELLED, sendCnt=2, command=NetworkAddressRequest [0000/0 -> EDD1/0, cluster=0000, TID=08, ieeeAddr=7CB03EAA0A00FD9A, requestType=0, startIndex=0]]
16:16:01.096 [DEBUG] [.transaction.ZigBeeTransactionManager] - transactionListenerRemoved: 0 outstanding
16:16:01.097 [DEBUG] [ee.transaction.ZigBeeTransactionQueue] - 7CB03EAA0A00FD9A: transactionComplete but not outstanding, state=CANCELLED, outstanding=0
16:16:01.097 [DEBUG] [.transaction.ZigBeeTransactionManager] - Transaction Manager: Send Next transaction. outstandingTransactions=0, outstandingQueues=0, sleepy=0/3
16:16:01.097 [DEBUG] [.transaction.ZigBeeTransactionManager] - Transaction Manager: Send Next transaction. outstandingTransactions=0, outstandingQueues=0, sleepy=0/3
16:16:01.097 [DEBUG] [ee.transaction.ZigBeeTransactionQueue] - Broadcast: Added transaction to queue, len=1, transaction=ZigBeeTransaction [ieeeAddress=null queueTime=0, state=WAITING, sendCnt=0, command=NetworkAddressRequest [0000/0 -> FFFF/0, cluster=0000, TID=--, ieeeAddr=7CB03EAA0A00FD9A, requestType=0, startIndex=0]]
16:16:01.097 [DEBUG] [.transaction.ZigBeeTransactionManager] - Transaction Manager: Send Next transaction. outstandingTransactions=0, outstandingQueues=1, sleepy=0/3
16:16:01.098 [DEBUG] [.transaction.ZigBeeTransactionManager] - FFFF/0: Sending ZigBeeTransaction [ieeeAddress=null queueTime=1, state=WAITING, sendCnt=0, command=NetworkAddressRequest [0000/0 -> FFFF/0, cluster=0000, TID=09, ieeeAddr=7CB03EAA0A00FD9A, requestType=0, startIndex=0]]
16:16:01.098 [DEBUG] [.transaction.ZigBeeTransactionManager] - transactionListenerAdded: 1 outstanding
16:16:01.099 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX CMD: NetworkAddressRequest [0000/0 -> FFFF/0, cluster=0000, TID=09, ieeeAddr=7CB03EAA0A00FD9A, requestType=0, startIndex=0]
16:16:01.099 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0000/0, destinationAddress=FFFF/0, profile=0000, cluster=0000, addressMode=DEVICE, radius=8, apsSecurity=false, ackRequest=true, apsCounter=0C, rssi=--, lqi=--, payload=09 9A FD 00 0A AA 3E B0 7C 00 00]
16:16:01.099 [DEBUG] [531.network.impl.CommandInterfaceImpl] - ->  AF_DATA_REQUEST (Packet: subsystem=null, length=21, apiId=24 01, data=FE 15 24 01 FF FF 00 00 00 00 0C 30 08 0B 09 9A FD 00 0A AA 3E B0 7C 00 00 33, checksum=33, error=false)
16:16:01.125 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-  AF_DATA_SRSP (FE 01 64 01 00 64)
16:16:01.126 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX STA: msgTag=09 state=TX_ACK
16:16:01.127 [DEBUG] [.transaction.ZigBeeTransactionManager] - notifyTransactionProgress: TID=09, state=TX_ACK, outstanding=1
16:16:01.128 [DEBUG] [.zigbee.transaction.ZigBeeTransaction] - Transaction state changed: nwk=FFFF, TID=09, event=TX_ACK, state=TRANSMITTED
16:16:01.156 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-- ZToolPacket (FE 03 45 C4 D1 ED 00 BE)
16:16:01.157 [DEBUG] [531.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=3, apiId=45 C4, data=FE 03 45 C4 D1 ED 00 BE, checksum=BE, error=false
16:16:01.157 [DEBUG] [ee.dongle.cc2531.ZigBeeDongleTiCc2531] - Unhandled ZToolPacket type 0x45c4
16:16:01.186 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-- ZToolPacket (FE 0D 45 80 00 9A FD 00 0A AA 3E B0 7C D1 ED 80 00 41)
16:16:01.186 [DEBUG] [531.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=13, apiId=45 80, data=FE 0D 45 80 00 9A FD 00 0A AA 3E B0 7C D1 ED 80 00 41, checksum=41, error=false
16:16:01.186 [DEBUG] [ee.dongle.cc2531.ZigBeeDongleTiCc2531] - Unhandled ZToolPacket type 0x4580
16:16:01.186 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-- ZToolPacket (FE 14 45 FF D1 ED 00 00 80 00 09 00 00 00 9A FD 00 0A AA 3E B0 7C D1 ED 12)
16:16:01.187 [DEBUG] [531.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=20, apiId=45 FF, data=FE 14 45 FF D1 ED 00 00 80 00 09 00 00 00 9A FD 00 0A AA 3E B0 7C D1 ED 12, checksum=12, error=false
16:16:01.187 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=EDD1/0, destinationAddress=0000/0, profile=0000, cluster=8000, addressMode=null, radius=0, apsSecurity=false, ackRequest=false, apsCounter=--, rssi=--, lqi=--, payload=00 00 9A FD 00 0A AA 3E B0 7C D1 ED]
16:16:01.187 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX CMD: NetworkAddressResponse [EDD1/0 -> 0000/0, cluster=8000, TID=00, status=SUCCESS, ieeeAddrRemoteDev=7CB03EAA0A00FD9A, nwkAddrRemoteDev=EDD1, startIndex=null, nwkAddrAssocDevList=[]]
16:16:01.188 [DEBUG] [.transaction.ZigBeeTransactionManager] - notifyTransactionCommand: NetworkAddressResponse [EDD1/0 -> 0000/0, cluster=8000, TID=00, status=SUCCESS, ieeeAddrRemoteDev=7CB03EAA0A00FD9A, nwkAddrRemoteDev=EDD1, startIndex=null, nwkAddrAssocDevList=[]]
16:16:01.188 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-- ZB_FIND_DEVICE_CONFIRM (FE 0B 46 85 01 D1 ED 9A FD 00 0A AA 3E B0 7C C0)
16:16:01.188 [DEBUG] [531.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=11, apiId=46 85, data=FE 0B 46 85 01 D1 ED 9A FD 00 0A AA 3E B0 7C C0, checksum=C0, error=false
16:16:01.188 [DEBUG] [ee.dongle.cc2531.ZigBeeDongleTiCc2531] - Unhandled ZToolPacket type 0x4685
16:16:09.135 [DEBUG] [.transaction.ZigBeeTransactionManager] - Transaction complete: ZigBeeTransaction [ieeeAddress=null queueTime=8038, state=FAILED, sendCnt=1, command=NetworkAddressRequest [0000/0 -> FFFF/0, cluster=0000, TID=09, ieeeAddr=7CB03EAA0A00FD9A, requestType=0, startIndex=0]]
16:16:09.135 [DEBUG] [.transaction.ZigBeeTransactionManager] - transactionListenerRemoved: 0 outstanding
16:16:09.135 [DEBUG] [ee.transaction.ZigBeeTransactionQueue] - Broadcast: transactionComplete, state=FAILED, outstanding=0
16:16:09.135 [DEBUG] [ee.transaction.ZigBeeTransactionQueue] - Broadcast: transactionComplete exceeded max retries 1
16:16:09.136 [DEBUG] [.zigbee.transaction.ZigBeeTransaction] - Transaction cancelled: ZigBeeTransaction [ieeeAddress=null queueTime=8038, state=CANCELLED, sendCnt=1, command=NetworkAddressRequest [0000/0 -> FFFF/0, cluster=0000, TID=09, ieeeAddr=7CB03EAA0A00FD9A, requestType=0, startIndex=0]]
16:16:09.136 [DEBUG] [discovery.ZigBeeNodeServiceDiscoverer] - 7CB03EAA0A00FD9A: Node SVC Discovery: NetworkAddressRequest returned null
16:16:09.136 [DEBUG] [.transaction.ZigBeeTransactionManager] - Transaction complete: ZigBeeTransaction [ieeeAddress=null queueTime=8039, state=CANCELLED, sendCnt=1, command=NetworkAddressRequest [0000/0 -> FFFF/0, cluster=0000, TID=09, ieeeAddr=7CB03EAA0A00FD9A, requestType=0, startIndex=0]]
16:16:09.136 [DEBUG] [.transaction.ZigBeeTransactionManager] - transactionListenerRemoved: 0 outstanding
16:16:09.136 [DEBUG] [discovery.ZigBeeNodeServiceDiscoverer] - 7CB03EAA0A00FD9A: Node SVC Discovery: NetworkAddressRequest failed after unicast and broadcast
16:16:09.136 [DEBUG] [ee.transaction.ZigBeeTransactionQueue] - Broadcast: transactionComplete but not outstanding, state=CANCELLED, outstanding=0
16:16:09.136 [DEBUG] [discovery.ZigBeeNodeServiceDiscoverer] - 7CB03EAA0A00FD9A: Node SVC Discovery: request NWK_ADDRESS failed. Retry 2, wait 2328ms before retry.
16:16:09.136 [DEBUG] [.transaction.ZigBeeTransactionManager] - Transaction Manager: Send Next transaction. outstandingTransactions=0, outstandingQueues=0, sleepy=0/3
16:16:09.136 [DEBUG] [.transaction.ZigBeeTransactionManager] - Transaction Manager: Send Next transaction. outstandingTransactions=0, outstandingQueues=0, sleepy=0/3
16:16:11.465 [DEBUG] [discovery.ZigBeeNodeServiceDiscoverer] - 7CB03EAA0A00FD9A: Node SVC Discovery: running NWK_ADDRESS
16:16:11.465 [DEBUG] [ee.transaction.ZigBeeTransactionQueue] - 7CB03EAA0A00FD9A: Added transaction to queue, len=1, transaction=ZigBeeTransaction [ieeeAddress=7CB03EAA0A00FD9A queueTime=0, state=WAITING, sendCnt=0, command=NetworkAddressRequest [0000/0 -> EDD1/0, cluster=0000, TID=--, ieeeAddr=7CB03EAA0A00FD9A, requestType=0, startIndex=0]]
16:16:11.465 [DEBUG] [.transaction.ZigBeeTransactionManager] - Transaction Manager: Send Next transaction. outstandingTransactions=0, outstandingQueues=1, sleepy=0/3
16:16:11.465 [DEBUG] [.transaction.ZigBeeTransactionManager] - EDD1/0: Sending ZigBeeTransaction [ieeeAddress=7CB03EAA0A00FD9A queueTime=0, state=WAITING, sendCnt=0, command=NetworkAddressRequest [0000/0 -> EDD1/0, cluster=0000, TID=0A, ieeeAddr=7CB03EAA0A00FD9A, requestType=0, startIndex=0]]
16:16:11.465 [DEBUG] [.transaction.ZigBeeTransactionManager] - transactionListenerAdded: 1 outstanding
16:16:11.466 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX CMD: NetworkAddressRequest [0000/0 -> EDD1/0, cluster=0000, TID=0A, ieeeAddr=7CB03EAA0A00FD9A, requestType=0, startIndex=0]
16:16:11.466 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0000/0, destinationAddress=EDD1/0, profile=0000, cluster=0000, addressMode=DEVICE, radius=8, apsSecurity=false, ackRequest=true, apsCounter=0D, rssi=--, lqi=--, payload=0A 9A FD 00 0A AA 3E B0 7C 00 00]
16:16:11.466 [DEBUG] [531.network.impl.CommandInterfaceImpl] - ->  AF_DATA_REQUEST (Packet: subsystem=null, length=21, apiId=24 01, data=FE 15 24 01 D1 ED 00 00 00 00 0D 30 08 0B 0A 9A FD 00 0A AA 3E B0 7C 00 00 0D, checksum=0D, error=false)
16:16:11.481 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-  AF_DATA_SRSP (FE 01 64 01 00 64)
16:16:11.482 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX STA: msgTag=0A state=TX_ACK
16:16:11.483 [DEBUG] [.transaction.ZigBeeTransactionManager] - notifyTransactionProgress: TID=0A, state=TX_ACK, outstanding=1
16:16:11.484 [DEBUG] [.zigbee.transaction.ZigBeeTransaction] - Transaction state changed: nwk=EDD1, TID=0A, event=TX_ACK, state=TRANSMITTED
16:16:11.529 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-- ZToolPacket (FE 03 45 C4 D1 ED 00 BE)
16:16:11.529 [DEBUG] [531.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=3, apiId=45 C4, data=FE 03 45 C4 D1 ED 00 BE, checksum=BE, error=false
16:16:11.529 [DEBUG] [ee.dongle.cc2531.ZigBeeDongleTiCc2531] - Unhandled ZToolPacket type 0x45c4
16:16:11.560 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-- ZToolPacket (FE 03 45 C4 D1 ED 00 BE)
16:16:11.560 [DEBUG] [531.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=3, apiId=45 C4, data=FE 03 45 C4 D1 ED 00 BE, checksum=BE, error=false
16:16:11.560 [DEBUG] [ee.dongle.cc2531.ZigBeeDongleTiCc2531] - Unhandled ZToolPacket type 0x45c4
16:16:11.591 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-- ZToolPacket (FE 0D 45 80 00 9A FD 00 0A AA 3E B0 7C D1 ED 02 00 C3)
16:16:11.591 [DEBUG] [531.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=13, apiId=45 80, data=FE 0D 45 80 00 9A FD 00 0A AA 3E B0 7C D1 ED 02 00 C3, checksum=C3, error=false
16:16:11.591 [DEBUG] [ee.dongle.cc2531.ZigBeeDongleTiCc2531] - Unhandled ZToolPacket type 0x4580
16:16:11.607 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-- ZToolPacket (FE 14 45 FF D1 ED 00 00 80 00 0A 00 00 00 9A FD 00 0A AA 3E B0 7C D1 ED 11)
16:16:11.607 [DEBUG] [531.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=20, apiId=45 FF, data=FE 14 45 FF D1 ED 00 00 80 00 0A 00 00 00 9A FD 00 0A AA 3E B0 7C D1 ED 11, checksum=11, error=false
16:16:11.607 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=EDD1/0, destinationAddress=0000/0, profile=0000, cluster=8000, addressMode=null, radius=0, apsSecurity=false, ackRequest=false, apsCounter=--, rssi=--, lqi=--, payload=00 00 9A FD 00 0A AA 3E B0 7C D1 ED]
16:16:11.608 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX CMD: NetworkAddressResponse [EDD1/0 -> 0000/0, cluster=8000, TID=00, status=SUCCESS, ieeeAddrRemoteDev=7CB03EAA0A00FD9A, nwkAddrRemoteDev=EDD1, startIndex=null, nwkAddrAssocDevList=[]]
16:16:11.608 [DEBUG] [.transaction.ZigBeeTransactionManager] - notifyTransactionCommand: NetworkAddressResponse [EDD1/0 -> 0000/0, cluster=8000, TID=00, status=SUCCESS, ieeeAddrRemoteDev=7CB03EAA0A00FD9A, nwkAddrRemoteDev=EDD1, startIndex=null, nwkAddrAssocDevList=[]]
16:16:11.608 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-- ZB_FIND_DEVICE_CONFIRM (FE 0B 46 85 01 D1 ED 9A FD 00 0A AA 3E B0 7C C0)
16:16:11.608 [DEBUG] [531.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=11, apiId=46 85, data=FE 0B 46 85 01 D1 ED 9A FD 00 0A AA 3E B0 7C C0, checksum=C0, error=false
16:16:11.608 [DEBUG] [ee.dongle.cc2531.ZigBeeDongleTiCc2531] - Unhandled ZToolPacket type 0x4685
16:16:15.985 [DEBUG] [531.network.impl.CommandInterfaceImpl] - <-- ZToolPacket (FE 01 45 CB 00 8F)
16:16:15.986 [DEBUG] [531.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=1, apiId=45 CB, data=FE 01 45 CB 00 8F, checksum=8F, error=false
16:16:15.986 [DEBUG] [ee.dongle.cc2531.ZigBeeDongleTiCc2531] - Unhandled ZToolPacket type 0x45cb