2019-01-04 17:39:59.519 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1

2019-01-04 17:39:59.521 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisDisplayNetworkInformationCommand [device=null, channel=null, power=null, panId=null, epanId=null]

2019-01-04 17:39:59.525 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+N?

2019-01-04 17:39:59.657 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:+N=COO,11,-07,7661,37FDDB4D24F01102

2019-01-04 17:39:59.660 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK

2019-01-04 17:39:59.663 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDisplayNetworkInformationCommand [device=COO, channel=11, power=-7, panId=30305, epanId=37FDDB4D24F01102, status=SUCCESS]

2019-01-04 17:40:00.484 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - Starting ZigBee scan for zigbee:coordinator_telegesis:04000B3A

2019-01-04 17:40:00.488 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - Permit join to 65532/0 for 60 seconds.

2019-01-04 17:40:00.490 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ManagementPermitJoiningRequest [0/0 -> 65532/0, cluster=0036, TID=CC, permitDuration=60, tcSignificance=true]

2019-01-04 17:40:00.493 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=65532/0, profile=0000, cluster=54, addressMode=DEVICE, radius=31, apsSecurity=false, apsCounter=204, payload=00 3C 01]

2019-01-04 17:40:00.495 [DEBUG] [ngle.telegesis.ZigBeeDongleTelegesis] - Telegesis send: TelegesisSendMulticastCommand [radius=31, address=65532, sourceEp=0, destEp=0, profileId=0, clusterId=54, messageData=00 3C 01]

2019-01-04 17:40:00.497 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1

2019-01-04 17:40:00.500 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisSendMulticastCommand [radius=31, address=65532, sourceEp=0, destEp=0, profileId=0, clusterId=54, messageData=00 3C 01]

2019-01-04 17:40:00.503 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+SENDMCASTB:03,1F,FFFC,00,00,0000,0036

<


2019-01-04 17:40:00.506 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ManagementPermitJoiningRequest [0/0 -> 0/0, cluster=0036, TID=CD, permitDuration=60, tcSignificance=true]

2019-01-04 17:40:00.509 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=54, addressMode=DEVICE, radius=31, apsSecurity=false, apsCounter=205, payload=00 3C 01]

2019-01-04 17:40:00.511 [DEBUG] [ngle.telegesis.ZigBeeDongleTelegesis] - Telegesis send: TelegesisSendUnicastCommand [address=0, sourceEp=0, destEp=0, profileId=0, clusterId=54, messageData=00 3C 01, messageId=null]

2019-01-04 17:40:00.513 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1

2019-01-04 17:40:00.632 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK

2019-01-04 17:40:00.634 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisSendMulticastCommand [radius=31, address=65532, sourceEp=0, destEp=0, profileId=0, clusterId=54, messageData=00 3C 01, status=SUCCESS]

2019-01-04 17:40:00.635 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisSendUnicastCommand [address=0, sourceEp=0, destEp=0, profileId=0, clusterId=54, messageData=00 3C 01, messageId=null]

2019-01-04 17:40:00.639 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+SENDUCASTB:03,0000,00,00,0000,0036

<


2019-01-04 17:40:00.799 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:SEQ:79

2019-01-04 17:40:00.800 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK

2019-01-04 17:40:00.802 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisSendUnicastCommand [address=0, sourceEp=0, destEp=0, profileId=0, clusterId=54, messageData=00 3C 01, messageId=121, status=SUCCESS]

2019-01-04 17:40:00.806 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:RX:0000,0000,00,00,8036,02:,00,FF

2019-01-04 17:40:00.808 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisReceiveMessageEvent [ieeeAddress=null, networkAddress=0, profileId=0, destinationEp=0, sourceEp=0, clusterId=32822, messageData=00 00, rssi=0, lqi=255]

2019-01-04 17:40:00.809 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=32822, addressMode=null, radius=0, apsSecurity=false, apsCounter=0, payload=00 00]

2019-01-04 17:40:00.811 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ManagementPermitJoiningResponse [0/0 -> 0/0, cluster=8036, TID=NULL, status=SUCCESS]

2019-01-04 17:40:00.816 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:ACK:79

2019-01-04 17:40:00.818 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisAckMessageEvent [messageId=121]

2019-01-04 17:40:00.819 [DEBUG] [ngle.telegesis.ZigBeeDongleTelegesis] - Unhandled Telegesis Frame: TelegesisAckMessageEvent [messageId=121]

2019-01-04 17:40:01.820 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1

2019-01-04 17:40:01.823 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisDisplayNetworkInformationCommand [device=null, channel=null, power=null, panId=null, epanId=null]

2019-01-04 17:40:01.827 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+N?

2019-01-04 17:40:01.962 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:+N=COO,11,-07,7661,37FDDB4D24F01102

2019-01-04 17:40:01.965 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK

2019-01-04 17:40:01.968 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDisplayNetworkInformationCommand [device=COO, channel=11, power=-7, panId=30305, epanId=37FDDB4D24F01102, status=SUCCESS]

2019-01-04 17:40:02.970 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1

2019-01-04 17:40:02.972 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisDisplayNetworkInformationCommand [device=null, channel=null, power=null, panId=null, epanId=null]

2019-01-04 17:40:02.976 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+N?

2019-01-04 17:40:03.109 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:+N=COO,11,-07,7661,37FDDB4D24F01102

2019-01-04 17:40:03.112 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK

2019-01-04 17:40:03.115 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDisplayNetworkInformationCommand [device=COO, channel=11, power=-7, panId=30305, epanId=37FDDB4D24F01102, status=SUCCESS]

2019-01-04 17:40:04.117 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1

2019-01-04 17:40:04.120 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisDisplayNetworkInformationCommand [device=null, channel=null, power=null, panId=null, epanId=null]

2019-01-04 17:40:04.123 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+N?

2019-01-04 17:40:04.256 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:NEWNODE:379D,000D6F000B9386E0,0000

2019-01-04 17:40:04.259 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDeviceJoinedNetworkEvent [networkAddress=14237, ieeeAddress=000D6F000B9386E0, parentAddress=0]

2019-01-04 17:40:04.261 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - 000D6F000B9386E0: nodeStatusUpdate - node status is UNSECURED_JOIN, network address is 14237.

2019-01-04 17:40:04.273 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:RX:000D6F000B9386E0,379D,0000,00,00,0013,0C:7à†“

o

€,-53,FF

2019-01-04 17:40:04.272 [DEBUG] [pp.discovery.ZigBeeNetworkDiscoverer] - 000D6F000B9386E0: Device status updated. NWK=14237

2019-01-04 17:40:04.276 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - 000D6F000B9386E0: Node 14237 added to the network

2019-01-04 17:40:04.276 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisReceiveMessageEvent [ieeeAddress=000D6F000B9386E0, networkAddress=14237, profileId=0, destinationEp=0, sourceEp=0, clusterId=19, messageData=81 9D 37 E0 86 93 0B 00 6F 0D 00 80, rssi=-83, lqi=255]

2019-01-04 17:40:04.279 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=14237/0, destinationAddress=0/0, profile=0000, cluster=19, addressMode=null, radius=0, apsSecurity=false, apsCounter=0, payload=81 9D 37 E0 86 93 0B 00 6F 0D 00 80]

2019-01-04 17:40:04.282 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: DeviceAnnounce [14237/0 -> 0/0, cluster=0013, TID=NULL, nwkAddrOfInterest=14237, ieeeAddr=000D6F000B9386E0, capability=128]

2019-01-04 17:40:04.290 [DEBUG] [p.discovery.ZigBeeDiscoveryExtension] - DISCOVERY Extension: Mesh related command received. Triggering mesh update.

2019-01-04 17:40:04.288 [DEBUG] [pp.discovery.ZigBeeNetworkDiscoverer] - 000D6F000B9386E0: Device announce received. NWK=14237

2019-01-04 17:40:04.297 [DEBUG] [p.discovery.ZigBeeDiscoveryExtension] - DISCOVERY Extension: Adding discoverer for 000D6F000B9386E0

2019-01-04 17:40:04.300 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - 000D6F000B9386E0: Node 14237 update

2019-01-04 17:40:04.302 [DEBUG] [iscovery.ZigBeeNodeServiceDiscoverer] - 000D6F000B9386E0: Node SVC Discovery: start discovery

2019-01-04 17:40:04.298 [INFO ] [bee.discovery.ZigBeeDiscoveryService] - 000D6F000B9386E0: Starting ZigBee device discovery

2019-01-04 17:40:04.303 [DEBUG] [p.discovery.ZigBeeDiscoveryExtension] - DISCOVERY Extension: Start mesh update task with interval of 300 seconds

2019-01-04 17:40:04.302 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:+N=COO,11,-07,7661,37FDDB4D24F01102

2019-01-04 17:40:04.306 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - 000D6F000B9386E0: Creating ZigBee device zigbee:device with bridge zigbee:coordinator_telegesis:04000B3A

2019-01-04 17:40:04.306 [DEBUG] [iscovery.ZigBeeNodeServiceDiscoverer] - 000D6F000B9386E0: Node SVC Discovery: scheduled [NWK_ADDRESS, ACTIVE_ENDPOINTS, NODE_DESCRIPTOR, POWER_DESCRIPTOR, NEIGHBORS]

2019-01-04 17:40:04.311 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK

2019-01-04 17:40:04.314 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDisplayNetworkInformationCommand [device=COO, channel=11, power=-7, panId=30305, epanId=37FDDB4D24F01102, status=SUCCESS]

2019-01-04 17:40:04.317 [INFO ] [g.discovery.internal.PersistentInbox] - Added new thing 'zigbee:device:04000B3A:000d6f000b9386e0' to inbox.

2019-01-04 17:40:04.320 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - 000D6F000B9386E0: Node discovery not complete

2019-01-04 17:40:04.318 [DEBUG] [p.discovery.ZigBeeDiscoveryExtension] - DISCOVERY Extension: Starting mesh update

2019-01-04 17:40:04.325 [DEBUG] [p.discovery.ZigBeeDiscoveryExtension] - DISCOVERY Extension: Starting mesh update for 000D6F000B9386E0

==> /var/log/openhab2/events.log <==

2019-01-04 17:40:04.325 [home.event.InboxAddedEvent] - Discovery Result with UID 'zigbee:device:04000B3A:000d6f000b9386e0' has been added.

==> /var/log/openhab2/openhab.log <==

2019-01-04 17:40:04.328 [DEBUG] [iscovery.ZigBeeNodeServiceDiscoverer] - 000D6F000B9386E0: Node SVC Discovery: Update mesh

2019-01-04 17:40:04.328 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Start.

2019-01-04 17:40:04.336 [DEBUG] [iscovery.ZigBeeNodeServiceDiscoverer] - 000D6F000B9386E0: Node SVC Discovery: already scheduled or running

2019-01-04 17:40:04.340 [DEBUG] [iscovery.ZigBeeNodeServiceDiscoverer] - 000D6F000B9386E0: Node SVC Discovery: scheduled [NWK_ADDRESS, ACTIVE_ENDPOINTS, NODE_DESCRIPTOR, POWER_DESCRIPTOR, NEIGHBORS, ROUTES]

2019-01-04 17:40:04.342 [DEBUG] [p.discovery.ZigBeeDiscoveryExtension] - DISCOVERY Extension: Starting mesh update for 000D6F00108DEAEA

2019-01-04 17:40:04.348 [DEBUG] [iscovery.ZigBeeNodeServiceDiscoverer] - 000D6F00108DEAEA: Node SVC Discovery: Update mesh

2019-01-04 17:40:04.350 [DEBUG] [iscovery.ZigBeeNodeServiceDiscoverer] - 000D6F00108DEAEA: Node SVC Discovery: scheduled [ROUTES, NEIGHBORS]

2019-01-04 17:40:04.360 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Done.

2019-01-04 17:40:04.370 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Start.

2019-01-04 17:40:04.375 [INFO ] [bee.discovery.ZigBeeDiscoveryService] - 000D6F000B9386E0: Starting ZigBee device discovery

2019-01-04 17:40:04.380 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - 000D6F000B9386E0: Creating ZigBee device zigbee:device with bridge zigbee:coordinator_telegesis:04000B3A

2019-01-04 17:40:04.390 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Done.

2019-01-04 17:40:04.400 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - 000D6F000B9386E0: Node discovery not complete

2019-01-04 17:40:05.213 [DEBUG] [iscovery.ZigBeeNodeServiceDiscoverer] - 000D6F000B9386E0: Node SVC Discovery: running

2019-01-04 17:40:05.219 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: NetworkAddressRequest [0/0 -> 65535/0, cluster=0000, TID=CE, ieeeAddr=000D6F000B9386E0, requestType=0, startIndex=0]

2019-01-04 17:40:05.223 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=65535/0, profile=0000, cluster=0, addressMode=DEVICE, radius=31, apsSecurity=false, apsCounter=206, payload=00 E0 86 93 0B 00 6F 0D 00 00 00]

2019-01-04 17:40:05.228 [DEBUG] [ngle.telegesis.ZigBeeDongleTelegesis] - Telegesis send: TelegesisSendMulticastCommand [radius=31, address=65535, sourceEp=0, destEp=0, profileId=0, clusterId=0, messageData=00 E0 86 93 0B 00 6F 0D 00 00 00]

2019-01-04 17:40:05.232 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1

2019-01-04 17:40:05.237 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisSendMulticastCommand [radius=31, address=65535, sourceEp=0, destEp=0, profileId=0, clusterId=0, messageData=00 E0 86 93 0B 00 6F 0D 00 00 00]

2019-01-04 17:40:05.245 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+SENDMCASTB:0B,1F,FFFF,00,00,0000,0000

à†“

o

2019-01-04 17:40:05.343 [DEBUG] [iscovery.ZigBeeNodeServiceDiscoverer] - 000D6F000B9386E0: Node SVC Discovery: running

2019-01-04 17:40:05.347 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: NetworkAddressRequest [0/0 -> 65535/0, cluster=0000, TID=CF, ieeeAddr=000D6F000B9386E0, requestType=0, startIndex=0]

2019-01-04 17:40:05.350 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=65535/0, profile=0000, cluster=0, addressMode=DEVICE, radius=31, apsSecurity=false, apsCounter=207, payload=00 E0 86 93 0B 00 6F 0D 00 00 00]

2019-01-04 17:40:05.353 [DEBUG] [ngle.telegesis.ZigBeeDongleTelegesis] - Telegesis send: TelegesisSendMulticastCommand [radius=31, address=65535, sourceEp=0, destEp=0, profileId=0, clusterId=0, messageData=00 E0 86 93 0B 00 6F 0D 00 00 00]

2019-01-04 17:40:05.355 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1

2019-01-04 17:40:05.402 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK

2019-01-04 17:40:05.422 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisSendMulticastCommand [radius=31, address=65535, sourceEp=0, destEp=0, profileId=0, clusterId=0, messageData=00 E0 86 93 0B 00 6F 0D 00 00 00, status=SUCCESS]

2019-01-04 17:40:05.425 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisSendMulticastCommand [radius=31, address=65535, sourceEp=0, destEp=0, profileId=0, clusterId=0, messageData=00 E0 86 93 0B 00 6F 0D 00 00 00]

2019-01-04 17:40:05.433 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+SENDMCASTB:0B,1F,FFFF,00,00,0000,0000

à†“

o

2019-01-04 17:40:05.441 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:RX:0000,0000,00,00,8000,0C:à†“

o

7,00,FF

2019-01-04 17:40:05.444 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisReceiveMessageEvent [ieeeAddress=null, networkAddress=0, profileId=0, destinationEp=0, sourceEp=0, clusterId=32768, messageData=00 00 E0 86 93 0B 00 6F 0D 00 9D 37, rssi=0, lqi=255]

2019-01-04 17:40:05.448 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=32768, addressMode=null, radius=0, apsSecurity=false, apsCounter=0, payload=00 00 E0 86 93 0B 00 6F 0D 00 9D 37]

2019-01-04 17:40:05.451 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: NetworkAddressResponse [0/0 -> 0/0, cluster=8000, TID=NULL, status=SUCCESS, ieeeAddrRemoteDev=000D6F000B9386E0, nwkAddrRemoteDev=14237, startIndex=null, nwkAddrAssocDevList=[]]

2019-01-04 17:40:05.462 [DEBUG] [zigbee.transaction.ZigBeeTransaction] - Transaction complete: NetworkAddressRequest [0/0 -> 65535/0, cluster=0000, TID=CF, ieeeAddr=000D6F000B9386E0, requestType=0, startIndex=0]

2019-01-04 17:40:05.467 [DEBUG] [zigbee.transaction.ZigBeeTransaction] - Transaction complete: NetworkAddressRequest [0/0 -> 65535/0, cluster=0000, TID=CE, ieeeAddr=000D6F000B9386E0, requestType=0, startIndex=0]

2019-01-04 17:40:05.471 [DEBUG] [iscovery.ZigBeeNodeServiceDiscoverer] - 14237: Node SVC Discovery: NetworkAddressRequest returned NetworkAddressResponse [0/0 -> 0/0, cluster=8000, TID=NULL, status=SUCCESS, ieeeAddrRemoteDev=000D6F000B9386E0, nwkAddrRemoteDev=14237, startIndex=null, nwkAddrAssocDevList=[]]

2019-01-04 17:40:05.473 [DEBUG] [iscovery.ZigBeeNodeServiceDiscoverer] - 14237: Node SVC Discovery: NetworkAddressRequest returned NetworkAddressResponse [0/0 -> 0/0, cluster=8000, TID=NULL, status=SUCCESS, ieeeAddrRemoteDev=000D6F000B9386E0, nwkAddrRemoteDev=14237, startIndex=null, nwkAddrAssocDevList=[]]

2019-01-04 17:40:05.473 [DEBUG] [iscovery.ZigBeeNodeServiceDiscoverer] - 000D6F000B9386E0: Node SVC Discovery: request NWK_ADDRESS successful. Advanced to ACTIVE_ENDPOINTS.

2019-01-04 17:40:05.476 [DEBUG] [iscovery.ZigBeeNodeServiceDiscoverer] - 000D6F000B9386E0: Node SVC Discovery: request NWK_ADDRESS successful. Advanced to ACTIVE_ENDPOINTS.

2019-01-04 17:40:05.476 [DEBUG] [iscovery.ZigBeeNodeServiceDiscoverer] - 000D6F000B9386E0: Node SVC Discovery: running

2019-01-04 17:40:05.480 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ActiveEndpointsRequest [0/0 -> 14237/0, cluster=0005, TID=D0, nwkAddrOfInterest=14237]

2019-01-04 17:40:05.480 [DEBUG] [iscovery.ZigBeeNodeServiceDiscoverer] - 000D6F000B9386E0: Node SVC Discovery: running

2019-01-04 17:40:05.483 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=14237/0, profile=0000, cluster=5, addressMode=DEVICE, radius=31, apsSecurity=false, apsCounter=208, payload=00 9D 37]

2019-01-04 17:40:05.484 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ActiveEndpointsRequest [0/0 -> 14237/0, cluster=0005, TID=D1, nwkAddrOfInterest=14237]

2019-01-04 17:40:05.486 [DEBUG] [ngle.telegesis.ZigBeeDongleTelegesis] - Telegesis send: TelegesisSendUnicastCommand [address=14237, sourceEp=0, destEp=0, profileId=0, clusterId=5, messageData=00 9D 37, messageId=null]

2019-01-04 17:40:05.488 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1

2019-01-04 17:40:05.490 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=14237/0, profile=0000, cluster=5, addressMode=DEVICE, radius=31, apsSecurity=false, apsCounter=209, payload=00 9D 37]

2019-01-04 17:40:05.494 [DEBUG] [ngle.telegesis.ZigBeeDongleTelegesis] - Telegesis send: TelegesisSendUnicastCommand [address=14237, sourceEp=0, destEp=0, profileId=0, clusterId=5, messageData=00 9D 37, messageId=null]

2019-01-04 17:40:05.496 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 2

2019-01-04 17:40:05.590 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK

2019-01-04 17:40:05.592 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisSendMulticastCommand [radius=31, address=65535, sourceEp=0, destEp=0, profileId=0, clusterId=0, messageData=00 E0 86 93 0B 00 6F 0D 00 00 00, status=SUCCESS]

2019-01-04 17:40:05.594 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisSendUnicastCommand [address=14237, sourceEp=0, destEp=0, profileId=0, clusterId=5, messageData=00 9D 37, messageId=null]

2019-01-04 17:40:05.597 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+SENDUCASTB:03,379D,00,00,0000,0005

7

2019-01-04 17:40:05.601 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:RX:0000,0000,00,00,8000,0C:à†“

o

7,00,FF

2019-01-04 17:40:05.604 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisReceiveMessageEvent [ieeeAddress=null, networkAddress=0, profileId=0, destinationEp=0, sourceEp=0, clusterId=32768, messageData=00 00 E0 86 93 0B 00 6F 0D 00 9D 37, rssi=0, lqi=255]

2019-01-04 17:40:05.606 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=32768, addressMode=null, radius=0, apsSecurity=false, apsCounter=0, payload=00 00 E0 86 93 0B 00 6F 0D 00 9D 37]

2019-01-04 17:40:05.608 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: NetworkAddressResponse [0/0 -> 0/0, cluster=8000, TID=NULL, status=SUCCESS, ieeeAddrRemoteDev=000D6F000B9386E0, nwkAddrRemoteDev=14237, startIndex=null, nwkAddrAssocDevList=[]]

2019-01-04 17:40:05.730 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:SEQ:80

2019-01-04 17:40:05.732 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK

2019-01-04 17:40:05.734 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisSendUnicastCommand [address=14237, sourceEp=0, destEp=0, profileId=0, clusterId=5, messageData=00 9D 37, messageId=128, status=SUCCESS]

2019-01-04 17:40:05.736 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisSendUnicastCommand [address=14237, sourceEp=0, destEp=0, profileId=0, clusterId=5, messageData=00 9D 37, messageId=null]

2019-01-04 17:40:05.738 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+SENDUCASTB:03,379D,00,00,0000,0005

7

2019-01-04 17:40:05.871 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:SEQ:81

2019-01-04 17:40:05.873 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK

2019-01-04 17:40:05.875 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisSendUnicastCommand [address=14237, sourceEp=0, destEp=0, profileId=0, clusterId=5, messageData=00 9D 37, messageId=129, status=SUCCESS]

2019-01-04 17:40:06.222 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:RX:379D,0000,00,00,8005,07:7
,-52,FF

2019-01-04 17:40:06.225 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisReceiveMessageEvent [ieeeAddress=null, networkAddress=14237, profileId=0, destinationEp=0, sourceEp=0, clusterId=32773, messageData=00 00 9D 37 02 01 02, rssi=-82, lqi=255]

2019-01-04 17:40:06.229 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=14237/0, destinationAddress=0/0, profile=0000, cluster=32773, addressMode=null, radius=0, apsSecurity=false, apsCounter=0, payload=00 00 9D 37 02 01 02]

2019-01-04 17:40:06.233 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ActiveEndpointsResponse [14237/0 -> 0/0, cluster=8005, TID=NULL, status=SUCCESS, nwkAddrOfInterest=14237, activeEpList=[1, 2]]

2019-01-04 17:40:06.237 [DEBUG] [zigbee.transaction.ZigBeeTransaction] - Transaction complete: ActiveEndpointsRequest [0/0 -> 14237/0, cluster=0005, TID=D0, nwkAddrOfInterest=14237]

2019-01-04 17:40:06.240 [DEBUG] [zigbee.transaction.ZigBeeTransaction] - Transaction complete: ActiveEndpointsRequest [0/0 -> 14237/0, cluster=0005, TID=D1, nwkAddrOfInterest=14237]

2019-01-04 17:40:06.240 [DEBUG] [iscovery.ZigBeeNodeServiceDiscoverer] - 000D6F000B9386E0: Node SVC Discovery: ActiveEndpointsResponse returned CommandResult [SUCCESS, ActiveEndpointsResponse [14237/0 -> 0/0, cluster=8005, TID=NULL, status=SUCCESS, nwkAddrOfInterest=14237, activeEpList=[1, 2]]]

2019-01-04 17:40:06.242 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:ACK:80

2019-01-04 17:40:06.244 [DEBUG] [iscovery.ZigBeeNodeServiceDiscoverer] - 000D6F000B9386E0: Node SVC Discovery: ActiveEndpointsResponse returned CommandResult [SUCCESS, ActiveEndpointsResponse [14237/0 -> 0/0, cluster=8005, TID=NULL, status=SUCCESS, nwkAddrOfInterest=14237, activeEpList=[1, 2]]]

2019-01-04 17:40:06.244 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: SimpleDescriptorRequest [0/0 -> 14237/0, cluster=0004, TID=D2, nwkAddrOfInterest=14237, endpoint=1]

2019-01-04 17:40:06.244 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisAckMessageEvent [messageId=128]

2019-01-04 17:40:06.246 [DEBUG] [ngle.telegesis.ZigBeeDongleTelegesis] - Unhandled Telegesis Frame: TelegesisAckMessageEvent [messageId=128]

2019-01-04 17:40:06.247 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=14237/0, profile=0000, cluster=4, addressMode=DEVICE, radius=31, apsSecurity=false, apsCounter=210, payload=00 9D 37 01]

2019-01-04 17:40:06.247 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: SimpleDescriptorRequest [0/0 -> 14237/0, cluster=0004, TID=D3, nwkAddrOfInterest=14237, endpoint=1]

2019-01-04 17:40:06.250 [DEBUG] [ngle.telegesis.ZigBeeDongleTelegesis] - Telegesis send: TelegesisSendUnicastCommand [address=14237, sourceEp=0, destEp=0, profileId=0, clusterId=4, messageData=00 9D 37 01, messageId=null]

2019-01-04 17:40:06.252 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=14237/0, profile=0000, cluster=4, addressMode=DEVICE, radius=31, apsSecurity=false, apsCounter=211, payload=00 9D 37 01]

2019-01-04 17:40:06.253 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1

2019-01-04 17:40:06.255 [DEBUG] [ngle.telegesis.ZigBeeDongleTelegesis] - Telegesis send: TelegesisSendUnicastCommand [address=14237, sourceEp=0, destEp=0, profileId=0, clusterId=4, messageData=00 9D 37 01, messageId=null]

2019-01-04 17:40:06.257 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:RX:379D,0000,00,00,8005,07:7
,-52,FF

2019-01-04 17:40:06.256 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisSendUnicastCommand [address=14237, sourceEp=0, destEp=0, profileId=0, clusterId=4, messageData=00 9D 37 01, messageId=null]

2019-01-04 17:40:06.259 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisReceiveMessageEvent [ieeeAddress=null, networkAddress=14237, profileId=0, destinationEp=0, sourceEp=0, clusterId=32773, messageData=00 00 9D 37 02 01 02, rssi=-82, lqi=255]

2019-01-04 17:40:06.259 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1

2019-01-04 17:40:06.262 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=14237/0, destinationAddress=0/0, profile=0000, cluster=32773, addressMode=null, radius=0, apsSecurity=false, apsCounter=0, payload=00 00 9D 37 02 01 02]

2019-01-04 17:40:06.264 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+SENDUCASTB:04,379D,00,00,0000,0004

7


2019-01-04 17:40:06.265 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ActiveEndpointsResponse [14237/0 -> 0/0, cluster=8005, TID=NULL, status=SUCCESS, nwkAddrOfInterest=14237, activeEpList=[1, 2]]

2019-01-04 17:40:06.274 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:ACK:81

2019-01-04 17:40:06.277 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisAckMessageEvent [messageId=129]

2019-01-04 17:40:06.281 [DEBUG] [ngle.telegesis.ZigBeeDongleTelegesis] - Unhandled Telegesis Frame: TelegesisAckMessageEvent [messageId=129]

2019-01-04 17:40:06.292 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:RX:379D,0000,00,00,8000,0C:à†“

o

7,-52,FF

2019-01-04 17:40:06.296 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisReceiveMessageEvent [ieeeAddress=null, networkAddress=14237, profileId=0, destinationEp=0, sourceEp=0, clusterId=32768, messageData=00 00 E0 86 93 0B 00 6F 0D 00 9D 37, rssi=-82, lqi=255]

2019-01-04 17:40:06.300 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=14237/0, destinationAddress=0/0, profile=0000, cluster=32768, addressMode=null, radius=0, apsSecurity=false, apsCounter=0, payload=00 00 E0 86 93 0B 00 6F 0D 00 9D 37]

2019-01-04 17:40:06.303 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: NetworkAddressResponse [14237/0 -> 0/0, cluster=8000, TID=NULL, status=SUCCESS, ieeeAddrRemoteDev=000D6F000B9386E0, nwkAddrRemoteDev=14237, startIndex=null, nwkAddrAssocDevList=[]]

2019-01-04 17:40:06.339 [DEBUG] [iscovery.ZigBeeNodeServiceDiscoverer] - 000D6F00108DEAEA: Node SVC Discovery: running

2019-01-04 17:40:06.341 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ManagementRoutingRequest [0/0 -> 0/0, cluster=0032, TID=D4, startIndex=0]

2019-01-04 17:40:06.343 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=50, addressMode=DEVICE, radius=31, apsSecurity=false, apsCounter=212, payload=00 00]

2019-01-04 17:40:06.345 [DEBUG] [ngle.telegesis.ZigBeeDongleTelegesis] - Telegesis send: TelegesisSendUnicastCommand [address=0, sourceEp=0, destEp=0, profileId=0, clusterId=50, messageData=00 00, messageId=null]

2019-01-04 17:40:06.346 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 2

2019-01-04 17:40:06.400 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:SEQ:82

2019-01-04 17:40:06.402 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK

2019-01-04 17:40:06.404 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisSendUnicastCommand [address=14237, sourceEp=0, destEp=0, profileId=0, clusterId=4, messageData=00 9D 37 01, messageId=130, status=SUCCESS]

2019-01-04 17:40:06.405 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisSendUnicastCommand [address=14237, sourceEp=0, destEp=0, profileId=0, clusterId=4, messageData=00 9D 37 01, messageId=null]

2019-01-04 17:40:06.409 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+SENDUCASTB:04,379D,00,00,0000,0004

7


2019-01-04 17:40:06.543 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:SEQ:83

2019-01-04 17:40:06.545 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK

2019-01-04 17:40:06.546 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisSendUnicastCommand [address=14237, sourceEp=0, destEp=0, profileId=0, clusterId=4, messageData=00 9D 37 01, messageId=131, status=SUCCESS]

2019-01-04 17:40:06.548 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisSendUnicastCommand [address=0, sourceEp=0, destEp=0, profileId=0, clusterId=50, messageData=00 00, messageId=null]

2019-01-04 17:40:06.551 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+SENDUCASTB:02,0000,00,00,0000,0032

2019-01-04 17:40:06.753 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:SEQ:84

2019-01-04 17:40:06.755 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK

2019-01-04 17:40:06.756 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisSendUnicastCommand [address=0, sourceEp=0, destEp=0, profileId=0, clusterId=50, messageData=00 00, messageId=132, status=SUCCESS]

2019-01-04 17:40:06.771 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:RX:0000,0000,00,00,8032,50:@,00,FF

2019-01-04 17:40:06.776 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisReceiveMessageEvent [ieeeAddress=null, networkAddress=0, profileId=0, destinationEp=0, sourceEp=0, clusterId=32818, messageData=00 00 40 00 0F 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00, rssi=0, lqi=255]

2019-01-04 17:40:06.782 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=32818, addressMode=null, radius=0, apsSecurity=false, apsCounter=0, payload=00 00 40 00 0F 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00]

2019-01-04 17:40:06.784 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ManagementRoutingResponse [0/0 -> 0/0, cluster=8032, TID=NULL, status=SUCCESS, routingTableEntries=64, startIndex=0, routingTableList=[RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0]]]

2019-01-04 17:40:06.789 [DEBUG] [zigbee.transaction.ZigBeeTransaction] - Transaction complete: ManagementRoutingRequest [0/0 -> 0/0, cluster=0032, TID=D4, startIndex=0]

2019-01-04 17:40:06.790 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:ACK:84

2019-01-04 17:40:06.792 [DEBUG] [iscovery.ZigBeeNodeServiceDiscoverer] - 000D6F00108DEAEA: Node SVC Discovery: ManagementRoutingRequest returned CommandResult [SUCCESS, ManagementRoutingResponse [0/0 -> 0/0, cluster=8032, TID=NULL, status=SUCCESS, routingTableEntries=64, startIndex=0, routingTableList=[RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0]]]]

2019-01-04 17:40:06.793 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisAckMessageEvent [messageId=132]

2019-01-04 17:40:06.794 [DEBUG] [ngle.telegesis.ZigBeeDongleTelegesis] - Unhandled Telegesis Frame: TelegesisAckMessageEvent [messageId=132]

2019-01-04 17:40:06.794 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ManagementRoutingRequest [0/0 -> 0/0, cluster=0032, TID=D5, startIndex=15]

2019-01-04 17:40:06.796 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=50, addressMode=DEVICE, radius=31, apsSecurity=false, apsCounter=213, payload=00 0F]

2019-01-04 17:40:06.798 [DEBUG] [ngle.telegesis.ZigBeeDongleTelegesis] - Telegesis send: TelegesisSendUnicastCommand [address=0, sourceEp=0, destEp=0, profileId=0, clusterId=50, messageData=00 0F, messageId=null]

2019-01-04 17:40:06.799 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1

2019-01-04 17:40:06.801 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisSendUnicastCommand [address=0, sourceEp=0, destEp=0, profileId=0, clusterId=50, messageData=00 0F, messageId=null]

2019-01-04 17:40:06.804 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+SENDUCASTB:02,0000,00,00,0000,0032



2019-01-04 17:40:07.006 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:SEQ:86

2019-01-04 17:40:07.008 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK

2019-01-04 17:40:07.010 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisSendUnicastCommand [address=0, sourceEp=0, destEp=0, profileId=0, clusterId=50, messageData=00 0F, messageId=134, status=SUCCESS]

2019-01-04 17:40:07.019 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:RX:0000,0000,00,00,8032,50:@,00,FF

2019-01-04 17:40:07.022 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisReceiveMessageEvent [ieeeAddress=null, networkAddress=0, profileId=0, destinationEp=0, sourceEp=0, clusterId=32818, messageData=00 00 40 0F 0F 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00, rssi=0, lqi=255]

2019-01-04 17:40:07.025 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=32818, addressMode=null, radius=0, apsSecurity=false, apsCounter=0, payload=00 00 40 0F 0F 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00]

2019-01-04 17:40:07.028 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ManagementRoutingResponse [0/0 -> 0/0, cluster=8032, TID=NULL, status=SUCCESS, routingTableEntries=64, startIndex=15, routingTableList=[RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0]]]

2019-01-04 17:40:07.031 [DEBUG] [zigbee.transaction.ZigBeeTransaction] - Transaction complete: ManagementRoutingRequest [0/0 -> 0/0, cluster=0032, TID=D5, startIndex=15]

2019-01-04 17:40:07.035 [DEBUG] [iscovery.ZigBeeNodeServiceDiscoverer] - 000D6F00108DEAEA: Node SVC Discovery: ManagementRoutingRequest returned CommandResult [SUCCESS, ManagementRoutingResponse [0/0 -> 0/0, cluster=8032, TID=NULL, status=SUCCESS, routingTableEntries=64, startIndex=15, routingTableList=[RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0]]]]

2019-01-04 17:40:07.036 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:ACK:86

2019-01-04 17:40:07.039 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisAckMessageEvent [messageId=134]

2019-01-04 17:40:07.039 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ManagementRoutingRequest [0/0 -> 0/0, cluster=0032, TID=D6, startIndex=30]

2019-01-04 17:40:07.041 [DEBUG] [ngle.telegesis.ZigBeeDongleTelegesis] - Unhandled Telegesis Frame: TelegesisAckMessageEvent [messageId=134]

2019-01-04 17:40:07.042 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=50, addressMode=DEVICE, radius=31, apsSecurity=false, apsCounter=214, payload=00 1E]

2019-01-04 17:40:07.044 [DEBUG] [ngle.telegesis.ZigBeeDongleTelegesis] - Telegesis send: TelegesisSendUnicastCommand [address=0, sourceEp=0, destEp=0, profileId=0, clusterId=50, messageData=00 1E, messageId=null]

2019-01-04 17:40:07.046 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1

2019-01-04 17:40:07.049 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisSendUnicastCommand [address=0, sourceEp=0, destEp=0, profileId=0, clusterId=50, messageData=00 1E, messageId=null]

2019-01-04 17:40:07.054 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+SENDUCASTB:02,0000,00,00,0000,0032



2019-01-04 17:40:07.259 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:NODELEFT:379D,000D6F000B9386E0

2019-01-04 17:40:07.261 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDeviceLeftNetworkEvent [networkAddress=14237, ieeeAddress=000D6F000B9386E0]

2019-01-04 17:40:07.264 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - 000D6F000B9386E0: nodeStatusUpdate - node status is DEVICE_LEFT, network address is 14237.

2019-01-04 17:40:07.266 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - 000D6F000B9386E0: Node 14237 is removed from the network

2019-01-04 17:40:07.268 [DEBUG] [p.discovery.ZigBeeDiscoveryExtension] - DISCOVERY Extension: Removing discoverer for 000D6F000B9386E0

2019-01-04 17:40:07.281 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Start.

2019-01-04 17:40:07.302 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Done.

2019-01-04 17:40:07.305 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:SEQ:88

2019-01-04 17:40:07.308 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK

2019-01-04 17:40:07.310 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisSendUnicastCommand [address=0, sourceEp=0, destEp=0, profileId=0, clusterId=50, messageData=00 1E, messageId=136, status=SUCCESS]

2019-01-04 17:40:07.321 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:RX:0000,0000,00,00,8032,50:@,00,FF

2019-01-04 17:40:07.326 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisReceiveMessageEvent [ieeeAddress=null, networkAddress=0, profileId=0, destinationEp=0, sourceEp=0, clusterId=32818, messageData=00 00 40 1E 0F 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00, rssi=0, lqi=255]

2019-01-04 17:40:07.332 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=32818, addressMode=null, radius=0, apsSecurity=false, apsCounter=0, payload=00 00 40 1E 0F 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00]

2019-01-04 17:40:07.335 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ManagementRoutingResponse [0/0 -> 0/0, cluster=8032, TID=NULL, status=SUCCESS, routingTableEntries=64, startIndex=30, routingTableList=[RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0]]]

2019-01-04 17:40:07.343 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:ACK:88

2019-01-04 17:40:07.343 [DEBUG] [zigbee.transaction.ZigBeeTransaction] - Transaction complete: ManagementRoutingRequest [0/0 -> 0/0, cluster=0032, TID=D6, startIndex=30]

2019-01-04 17:40:07.345 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisAckMessageEvent [messageId=136]

2019-01-04 17:40:07.346 [DEBUG] [iscovery.ZigBeeNodeServiceDiscoverer] - 000D6F00108DEAEA: Node SVC Discovery: ManagementRoutingRequest returned CommandResult [SUCCESS, ManagementRoutingResponse [0/0 -> 0/0, cluster=8032, TID=NULL, status=SUCCESS, routingTableEntries=64, startIndex=30, routingTableList=[RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0]]]]

2019-01-04 17:40:07.347 [DEBUG] [ngle.telegesis.ZigBeeDongleTelegesis] - Unhandled Telegesis Frame: TelegesisAckMessageEvent [messageId=136]

2019-01-04 17:40:07.350 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ManagementRoutingRequest [0/0 -> 0/0, cluster=0032, TID=D7, startIndex=45]

2019-01-04 17:40:07.352 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=50, addressMode=DEVICE, radius=31, apsSecurity=false, apsCounter=215, payload=00 2D]

2019-01-04 17:40:07.353 [DEBUG] [ngle.telegesis.ZigBeeDongleTelegesis] - Telegesis send: TelegesisSendUnicastCommand [address=0, sourceEp=0, destEp=0, profileId=0, clusterId=50, messageData=00 2D, messageId=null]

2019-01-04 17:40:07.355 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1

2019-01-04 17:40:07.356 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisSendUnicastCommand [address=0, sourceEp=0, destEp=0, profileId=0, clusterId=50, messageData=00 2D, messageId=null]

2019-01-04 17:40:07.359 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+SENDUCASTB:02,0000,00,00,0000,0032

-

2019-01-04 17:40:07.561 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:SEQ:8A

2019-01-04 17:40:07.562 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK

2019-01-04 17:40:07.563 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisSendUnicastCommand [address=0, sourceEp=0, destEp=0, profileId=0, clusterId=50, messageData=00 2D, messageId=138, status=SUCCESS]

2019-01-04 17:40:07.568 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:RX:0000,0000,00,00,8032,50:@-,00,FF

2019-01-04 17:40:07.571 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisReceiveMessageEvent [ieeeAddress=null, networkAddress=0, profileId=0, destinationEp=0, sourceEp=0, clusterId=32818, messageData=00 00 40 2D 0F 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00, rssi=0, lqi=255]

2019-01-04 17:40:07.574 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=32818, addressMode=null, radius=0, apsSecurity=false, apsCounter=0, payload=00 00 40 2D 0F 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00]

2019-01-04 17:40:07.576 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ManagementRoutingResponse [0/0 -> 0/0, cluster=8032, TID=NULL, status=SUCCESS, routingTableEntries=64, startIndex=45, routingTableList=[RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0]]]

2019-01-04 17:40:07.579 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:ACK:8A

2019-01-04 17:40:07.579 [DEBUG] [zigbee.transaction.ZigBeeTransaction] - Transaction complete: ManagementRoutingRequest [0/0 -> 0/0, cluster=0032, TID=D7, startIndex=45]

2019-01-04 17:40:07.580 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisAckMessageEvent [messageId=138]

2019-01-04 17:40:07.581 [DEBUG] [iscovery.ZigBeeNodeServiceDiscoverer] - 000D6F00108DEAEA: Node SVC Discovery: ManagementRoutingRequest returned CommandResult [SUCCESS, ManagementRoutingResponse [0/0 -> 0/0, cluster=8032, TID=NULL, status=SUCCESS, routingTableEntries=64, startIndex=45, routingTableList=[RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0]]]]

2019-01-04 17:40:07.582 [DEBUG] [ngle.telegesis.ZigBeeDongleTelegesis] - Unhandled Telegesis Frame: TelegesisAckMessageEvent [messageId=138]

2019-01-04 17:40:07.583 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ManagementRoutingRequest [0/0 -> 0/0, cluster=0032, TID=D8, startIndex=60]

2019-01-04 17:40:07.587 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=50, addressMode=DEVICE, radius=31, apsSecurity=false, apsCounter=216, payload=00 3C]

2019-01-04 17:40:07.589 [DEBUG] [ngle.telegesis.ZigBeeDongleTelegesis] - Telegesis send: TelegesisSendUnicastCommand [address=0, sourceEp=0, destEp=0, profileId=0, clusterId=50, messageData=00 3C, messageId=null]

2019-01-04 17:40:07.591 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1

2019-01-04 17:40:07.592 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisSendUnicastCommand [address=0, sourceEp=0, destEp=0, profileId=0, clusterId=50, messageData=00 3C, messageId=null]

2019-01-04 17:40:07.596 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+SENDUCASTB:02,0000,00,00,0000,0032

<

2019-01-04 17:40:07.767 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:SEQ:8C

2019-01-04 17:40:07.769 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK

2019-01-04 17:40:07.770 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisSendUnicastCommand [address=0, sourceEp=0, destEp=0, profileId=0, clusterId=50, messageData=00 3C, messageId=140, status=SUCCESS]

2019-01-04 17:40:07.774 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:RX:0000,0000,00,00,8032,19:@<,00,FF

2019-01-04 17:40:07.775 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisReceiveMessageEvent [ieeeAddress=null, networkAddress=0, profileId=0, destinationEp=0, sourceEp=0, clusterId=32818, messageData=00 00 40 3C 04 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00, rssi=0, lqi=255]

2019-01-04 17:40:07.778 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=32818, addressMode=null, radius=0, apsSecurity=false, apsCounter=0, payload=00 00 40 3C 04 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00]

2019-01-04 17:40:07.779 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ManagementRoutingResponse [0/0 -> 0/0, cluster=8032, TID=NULL, status=SUCCESS, routingTableEntries=64, startIndex=60, routingTableList=[RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0]]]

2019-01-04 17:40:07.783 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:ACK:8C

2019-01-04 17:40:07.783 [DEBUG] [zigbee.transaction.ZigBeeTransaction] - Transaction complete: ManagementRoutingRequest [0/0 -> 0/0, cluster=0032, TID=D8, startIndex=60]

2019-01-04 17:40:07.784 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisAckMessageEvent [messageId=140]

2019-01-04 17:40:07.785 [DEBUG] [ngle.telegesis.ZigBeeDongleTelegesis] - Unhandled Telegesis Frame: TelegesisAckMessageEvent [messageId=140]

2019-01-04 17:40:07.785 [DEBUG] [iscovery.ZigBeeNodeServiceDiscoverer] - 000D6F00108DEAEA: Node SVC Discovery: ManagementRoutingRequest returned CommandResult [SUCCESS, ManagementRoutingResponse [0/0 -> 0/0, cluster=8032, TID=NULL, status=SUCCESS, routingTableEntries=64, startIndex=60, routingTableList=[RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0]]]]

2019-01-04 17:40:07.788 [DEBUG] [iscovery.ZigBeeNodeServiceDiscoverer] - 000D6F00108DEAEA: Node SVC Discovery: ManagementLqiRequest complete [1 routes]

2019-01-04 17:40:07.790 [DEBUG] [com.zsmartsystems.zigbee.ZigBeeNode ] - 000D6F00108DEAEA: Routing table NEW: [RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0]]

2019-01-04 17:40:07.792 [DEBUG] [com.zsmartsystems.zigbee.ZigBeeNode ] - 000D6F00108DEAEA: Routing table OLD: [RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0]]

2019-01-04 17:40:07.793 [DEBUG] [com.zsmartsystems.zigbee.ZigBeeNode ] - 000D6F00108DEAEA: Routing table unchanged

2019-01-04 17:40:07.795 [DEBUG] [iscovery.ZigBeeNodeServiceDiscoverer] - 000D6F00108DEAEA: Node SVC Discovery: request ROUTES successful. Advanced to NEIGHBORS.

2019-01-04 17:40:07.797 [DEBUG] [iscovery.ZigBeeNodeServiceDiscoverer] - 000D6F00108DEAEA: Node SVC Discovery: running

2019-01-04 17:40:07.799 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ManagementLqiRequest [0/0 -> 0/0, cluster=0031, TID=D9, startIndex=0]

2019-01-04 17:40:07.800 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=49, addressMode=DEVICE, radius=31, apsSecurity=false, apsCounter=217, payload=00 00]

2019-01-04 17:40:07.802 [DEBUG] [ngle.telegesis.ZigBeeDongleTelegesis] - Telegesis send: TelegesisSendUnicastCommand [address=0, sourceEp=0, destEp=0, profileId=0, clusterId=49, messageData=00 00, messageId=null]

2019-01-04 17:40:07.804 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1

2019-01-04 17:40:07.805 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisSendUnicastCommand [address=0, sourceEp=0, destEp=0, profileId=0, clusterId=49, messageData=00 00, messageId=null]

2019-01-04 17:40:07.808 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+SENDUCASTB:02,0000,00,00,0000,0031

2019-01-04 17:40:07.970 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:SEQ:8E

2019-01-04 17:40:07.972 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK

2019-01-04 17:40:07.974 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisSendUnicastCommand [address=0, sourceEp=0, destEp=0, profileId=0, clusterId=49, messageData=00 00, messageId=142, status=SUCCESS]

2019-01-04 17:40:07.977 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:RX:0000,0000,00,00,8031,05:,00,FF

2019-01-04 17:40:07.979 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisReceiveMessageEvent [ieeeAddress=null, networkAddress=0, profileId=0, destinationEp=0, sourceEp=0, clusterId=32817, messageData=00 00 00 00 00, rssi=0, lqi=255]

2019-01-04 17:40:07.981 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=32817, addressMode=null, radius=0, apsSecurity=false, apsCounter=0, payload=00 00 00 00 00]

2019-01-04 17:40:07.984 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ManagementLqiResponse [0/0 -> 0/0, cluster=8031, TID=NULL, status=SUCCESS, neighborTableEntries=0, startIndex=0, neighborTableList=[]]

2019-01-04 17:40:07.989 [DEBUG] [zigbee.transaction.ZigBeeTransaction] - Transaction complete: ManagementLqiRequest [0/0 -> 0/0, cluster=0031, TID=D9, startIndex=0]

2019-01-04 17:40:07.991 [DEBUG] [iscovery.ZigBeeNodeServiceDiscoverer] - 000D6F00108DEAEA: Node SVC Discovery: ManagementLqiRequest response CommandResult [SUCCESS, ManagementLqiResponse [0/0 -> 0/0, cluster=8031, TID=NULL, status=SUCCESS, neighborTableEntries=0, startIndex=0, neighborTableList=[]]]

2019-01-04 17:40:07.991 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:ACK:8E

2019-01-04 17:40:07.993 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisAckMessageEvent [messageId=142]

2019-01-04 17:40:07.993 [DEBUG] [iscovery.ZigBeeNodeServiceDiscoverer] - 000D6F00108DEAEA: Node SVC Discovery: ManagementLqiRequest complete [0 neighbors]

2019-01-04 17:40:07.995 [DEBUG] [ngle.telegesis.ZigBeeDongleTelegesis] - Unhandled Telegesis Frame: TelegesisAckMessageEvent [messageId=142]

2019-01-04 17:40:07.995 [DEBUG] [com.zsmartsystems.zigbee.ZigBeeNode ] - 000D6F00108DEAEA: Neighbor table unchanged

2019-01-04 17:40:07.997 [DEBUG] [iscovery.ZigBeeNodeServiceDiscoverer] - 000D6F00108DEAEA: Node SVC Discovery: request NEIGHBORS successful. Advanced to null.

2019-01-04 17:40:07.999 [DEBUG] [iscovery.ZigBeeNodeServiceDiscoverer] - 000D6F00108DEAEA: Node SVC Discovery: running

2019-01-04 17:40:08.001 [DEBUG] [iscovery.ZigBeeNodeServiceDiscoverer] - 000D6F00108DEAEA: Node SVC Discovery: complete

2019-01-04 17:40:08.003 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - 000D6F00108DEAEA: Node 0 update

2019-01-04 17:40:08.012 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Start.

2019-01-04 17:40:08.023 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Done.

2019-01-04 17:40:08.997 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1

2019-01-04 17:40:08.999 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisDisplayNetworkInformationCommand [device=null, channel=null, power=null, panId=null, epanId=null]

2019-01-04 17:40:09.004 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+N?

2019-01-04 17:40:09.141 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:+N=COO,11,-07,7661,37FDDB4D24F01102

2019-01-04 17:40:09.145 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK

2019-01-04 17:40:09.152 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDisplayNetworkInformationCommand [device=COO, channel=11, power=-7, panId=30305, epanId=37FDDB4D24F01102, status=SUCCESS]

2019-01-04 17:40:10.155 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1

2019-01-04 17:40:10.157 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisDisplayNetworkInformationCommand [device=null, channel=null, power=null, panId=null, epanId=null]

2019-01-04 17:40:10.162 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+N?

2019-01-04 17:40:10.299 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:+N=COO,11,-07,7661,37FDDB4D24F01102

2019-01-04 17:40:10.303 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK

2019-01-04 17:40:10.306 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDisplayNetworkInformationCommand [device=COO, channel=11, power=-7, panId=30305, epanId=37FDDB4D24F01102, status=SUCCESS]

==> /var/log/openhab2/events.log <==

2019-01-04 17:40:11.099 [me.event.InboxRemovedEvent] - Discovery Result with UID 'zigbee:device:04000B3A:000d6f000b9386e0' has been removed.

==> /var/log/openhab2/openhab.log <==

2019-01-04 17:40:11.117 [DEBUG] [org.openhab.binding.zigbee          ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.config.core.ConfigDescriptionProvider}={service.id=379, service.bundleid=211, service.scope=singleton} - org.openhab.binding.zigbee

2019-01-04 17:40:11.158 [DEBUG] [org.openhab.binding.zigbee          ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.core.thing.type.DynamicStateDescriptionProvider}={service.id=380, service.bundleid=211, service.scope=singleton} - org.openhab.binding.zigbee

2019-01-04 17:40:11.180 [DEBUG] [org.openhab.binding.zigbee          ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.core.thing.binding.firmware.FirmwareUpdateHandler}={service.id=381, service.bundleid=211, service.scope=singleton} - org.openhab.binding.zigbee

2019-01-04 17:40:11.199 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F000B9386E0: Initializing ZigBee thing handler zigbee:device:04000B3A:000d6f000b9386e0

2019-01-04 17:40:11.205 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F000B9386E0: Coordinator status changed to ONLINE.

2019-01-04 17:40:11.207 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F000B9386E0: Coordinator is ONLINE. Starting device initialisation.

2019-01-04 17:40:11.211 [DEBUG] [pp.discovery.ZigBeeNetworkDiscoverer] - 000D6F000B9386E0: NWK Discovery starting node rediscovery

2019-01-04 17:40:11.214 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: NetworkAddressRequest [0/0 -> 65533/0, cluster=0000, TID=DA, ieeeAddr=000D6F000B9386E0, requestType=0, startIndex=0]

2019-01-04 17:40:11.217 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=65533/0, profile=0000, cluster=0, addressMode=DEVICE, radius=31, apsSecurity=false, apsCounter=218, payload=00 E0 86 93 0B 00 6F 0D 00 00 00]

2019-01-04 17:40:11.220 [DEBUG] [ngle.telegesis.ZigBeeDongleTelegesis] - Telegesis send: TelegesisSendMulticastCommand [radius=31, address=65533, sourceEp=0, destEp=0, profileId=0, clusterId=0, messageData=00 E0 86 93 0B 00 6F 0D 00 00 00]

2019-01-04 17:40:11.223 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1

2019-01-04 17:40:11.227 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F000B9386E0: Node not found - deferring handler initialisation

2019-01-04 17:40:11.228 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisSendMulticastCommand [radius=31, address=65533, sourceEp=0, destEp=0, profileId=0, clusterId=0, messageData=00 E0 86 93 0B 00 6F 0D 00 00 00]

2019-01-04 17:40:11.230 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+SENDMCASTB:0B,1F,FFFD,00,00,0000,0000

à†“

o

==> /var/log/openhab2/events.log <==

2019-01-04 17:40:11.204 [hingStatusInfoChangedEvent] - 'zigbee:device:04000B3A:000d6f000b9386e0' changed from UNINITIALIZED to INITIALIZING

2019-01-04 17:40:11.246 [hingStatusInfoChangedEvent] - 'zigbee:device:04000B3A:000d6f000b9386e0' changed from INITIALIZING to UNKNOWN

2019-01-04 17:40:11.250 [hingStatusInfoChangedEvent] - 'zigbee:device:04000B3A:000d6f000b9386e0' changed from UNKNOWN to OFFLINE: Node is not found on network

2019-01-04 17:40:11.253 [nt.FirmwareStatusInfoEvent] - Firmware status of thing zigbee:device:04000B3A:000d6f000b9386e0 changed to UNKNOWN.

==> /var/log/openhab2/openhab.log <==

2019-01-04 17:40:11.363 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK

2019-01-04 17:40:11.365 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisSendMulticastCommand [radius=31, address=65533, sourceEp=0, destEp=0, profileId=0, clusterId=0, messageData=00 E0 86 93 0B 00 6F 0D 00 00 00, status=SUCCESS]

2019-01-04 17:40:12.367 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1

2019-01-04 17:40:12.369 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisDisplayNetworkInformationCommand [device=null, channel=null, power=null, panId=null, epanId=null]

2019-01-04 17:40:12.372 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+N?

2019-01-04 17:40:12.506 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:+N=COO,11,-07,7661,37FDDB4D24F01102

2019-01-04 17:40:12.508 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK

2019-01-04 17:40:12.511 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDisplayNetworkInformationCommand [device=COO, channel=11, power=-7, panId=30305, epanId=37FDDB4D24F01102, status=SUCCESS]

2019-01-04 17:40:13.513 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1

2019-01-04 17:40:13.515 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisDisplayNetworkInformationCommand [device=null, channel=null, power=null, panId=null, epanId=null]

2019-01-04 17:40:13.518 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+N?

2019-01-04 17:40:13.725 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:NEWNODE:A20C,000D6F000B9386E0,0000

2019-01-04 17:40:13.728 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDeviceJoinedNetworkEvent [networkAddress=41484, ieeeAddress=000D6F000B9386E0, parentAddress=0]

2019-01-04 17:40:13.730 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - 000D6F000B9386E0: nodeStatusUpdate - node status is UNSECURED_JOIN, network address is 41484.

2019-01-04 17:40:13.732 [DEBUG] [pp.discovery.ZigBeeNetworkDiscoverer] - 000D6F000B9386E0: Device status updated. NWK=41484

2019-01-04 17:40:13.735 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - 000D6F000B9386E0: Node 41484 added to the network

2019-01-04 17:40:13.736 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:+N=COO,11,-07,7661,37FDDB4D24F01102

2019-01-04 17:40:13.738 [DEBUG] [p.discovery.ZigBeeDiscoveryExtension] - DISCOVERY Extension: Adding discoverer for 000D6F000B9386E0

2019-01-04 17:40:13.741 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK

2019-01-04 17:40:13.741 [DEBUG] [iscovery.ZigBeeNodeServiceDiscoverer] - 000D6F000B9386E0: Node SVC Discovery: start discovery

2019-01-04 17:40:13.744 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDisplayNetworkInformationCommand [device=COO, channel=11, power=-7, panId=30305, epanId=37FDDB4D24F01102, status=SUCCESS]

2019-01-04 17:40:13.746 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F000B9386E0: Node updated - ZigBeeNode [IEEE=000D6F000B9386E0, NWK=A20C, Type=UNKNOWN]

2019-01-04 17:40:13.749 [INFO ] [bee.discovery.ZigBeeDiscoveryService] - 000D6F000B9386E0: Starting ZigBee device discovery

2019-01-04 17:40:13.744 [DEBUG] [iscovery.ZigBeeNodeServiceDiscoverer] - 000D6F000B9386E0: Node SVC Discovery: scheduled [NWK_ADDRESS, ACTIVE_ENDPOINTS, NODE_DESCRIPTOR, POWER_DESCRIPTOR, NEIGHBORS]

2019-01-04 17:40:13.752 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - 000D6F000B9386E0: Creating ZigBee device zigbee:device with bridge zigbee:coordinator_telegesis:04000B3A

2019-01-04 17:40:13.752 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:RX:000D6F000B9386E0,A20C,0000,00,00,0013,0C:‚

¢à†“

o

€,-52,EF

2019-01-04 17:40:13.756 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisReceiveMessageEvent [ieeeAddress=000D6F000B9386E0, networkAddress=41484, profileId=0, destinationEp=0, sourceEp=0, clusterId=19, messageData=82 0C A2 E0 86 93 0B 00 6F 0D 00 80, rssi=-82, lqi=239]

2019-01-04 17:40:13.756 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - 000D6F000B9386E0: Node discovery not complete

2019-01-04 17:40:13.760 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=41484/0, destinationAddress=0/0, profile=0000, cluster=19, addressMode=null, radius=0, apsSecurity=false, apsCounter=0, payload=82 0C A2 E0 86 93 0B 00 6F 0D 00 80]

2019-01-04 17:40:13.763 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: DeviceAnnounce [41484/0 -> 0/0, cluster=0013, TID=NULL, nwkAddrOfInterest=41484, ieeeAddr=000D6F000B9386E0, capability=128]

==> /var/log/openhab2/events.log <==

2019-01-04 17:40:13.767 [me.event.ThingUpdatedEvent] - Thing 'zigbee:device:04000B3A:000d6f000b9386e0' has been updated.

==> /var/log/openhab2/openhab.log <==

2019-01-04 17:40:13.770 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Start.

2019-01-04 17:40:13.776 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F000B9386E0: Node has not finished discovery

2019-01-04 17:40:13.778 [DEBUG] [p.discovery.ZigBeeDiscoveryExtension] - DISCOVERY Extension: Mesh related command received. Triggering mesh update.

2019-01-04 17:40:13.780 [DEBUG] [p.discovery.ZigBeeDiscoveryExtension] - DISCOVERY Extension: Start mesh update task with interval of 300 seconds

2019-01-04 17:40:13.770 [DEBUG] [pp.discovery.ZigBeeNetworkDiscoverer] - 000D6F000B9386E0: Device announce received. NWK=41484

2019-01-04 17:40:13.789 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - 000D6F000B9386E0: Node 41484 update

2019-01-04 17:40:13.792 [DEBUG] [p.discovery.ZigBeeDiscoveryExtension] - DISCOVERY Extension: Starting mesh update

2019-01-04 17:40:13.796 [DEBUG] [p.discovery.ZigBeeDiscoveryExtension] - DISCOVERY Extension: Starting mesh update for 000D6F000B9386E0

==> /var/log/openhab2/events.log <==

2019-01-04 17:40:13.814 [hingStatusInfoChangedEvent] - 'zigbee:device:04000B3A:000d6f000b9386e0' changed from OFFLINE: Node is not found on network to OFFLINE: Node has not completed discovery

==> /var/log/openhab2/openhab.log <==

2019-01-04 17:40:13.818 [DEBUG] [iscovery.ZigBeeNodeServiceDiscoverer] - 000D6F000B9386E0: Node SVC Discovery: Update mesh

2019-01-04 17:40:13.825 [DEBUG] [iscovery.ZigBeeNodeServiceDiscoverer] - 000D6F000B9386E0: Node SVC Discovery: already scheduled or running

2019-01-04 17:40:13.830 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Done.

2019-01-04 17:40:13.830 [DEBUG] [iscovery.ZigBeeNodeServiceDiscoverer] - 000D6F000B9386E0: Node SVC Discovery: scheduled [NWK_ADDRESS, ACTIVE_ENDPOINTS, NODE_DESCRIPTOR, POWER_DESCRIPTOR, NEIGHBORS, ROUTES]

2019-01-04 17:40:13.833 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F000B9386E0: Node updated - ZigBeeNode [IEEE=000D6F000B9386E0, NWK=A20C, Type=UNKNOWN]

2019-01-04 17:40:13.843 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Start.

2019-01-04 17:40:13.846 [DEBUG] [p.discovery.ZigBeeDiscoveryExtension] - DISCOVERY Extension: Starting mesh update for 000D6F00108DEAEA

2019-01-04 17:40:13.845 [INFO ] [bee.discovery.ZigBeeDiscoveryService] - 000D6F000B9386E0: Starting ZigBee device discovery

2019-01-04 17:40:13.848 [DEBUG] [iscovery.ZigBeeNodeServiceDiscoverer] - 000D6F00108DEAEA: Node SVC Discovery: Update mesh

2019-01-04 17:40:13.849 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - 000D6F000B9386E0: Creating ZigBee device zigbee:device with bridge zigbee:coordinator_telegesis:04000B3A

2019-01-04 17:40:13.853 [DEBUG] [iscovery.ZigBeeNodeServiceDiscoverer] - 000D6F00108DEAEA: Node SVC Discovery: scheduled [ROUTES, NEIGHBORS]

2019-01-04 17:40:13.853 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F000B9386E0: Node has not finished discovery

2019-01-04 17:40:13.858 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - 000D6F000B9386E0: Node discovery not complete

2019-01-04 17:40:13.868 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Done.

2019-01-04 17:40:14.167 [DEBUG] [iscovery.ZigBeeNodeServiceDiscoverer] - 000D6F000B9386E0: Node SVC Discovery: running

2019-01-04 17:40:14.177 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: NetworkAddressRequest [0/0 -> 65535/0, cluster=0000, TID=DB, ieeeAddr=000D6F000B9386E0, requestType=0, startIndex=0]

2019-01-04 17:40:14.181 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=65535/0, profile=0000, cluster=0, addressMode=DEVICE, radius=31, apsSecurity=false, apsCounter=219, payload=00 E0 86 93 0B 00 6F 0D 00 00 00]

2019-01-04 17:40:14.183 [DEBUG] [ngle.telegesis.ZigBeeDongleTelegesis] - Telegesis send: TelegesisSendMulticastCommand [radius=31, address=65535, sourceEp=0, destEp=0, profileId=0, clusterId=0, messageData=00 E0 86 93 0B 00 6F 0D 00 00 00]

2019-01-04 17:40:14.186 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1

2019-01-04 17:40:14.189 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisSendMulticastCommand [radius=31, address=65535, sourceEp=0, destEp=0, profileId=0, clusterId=0, messageData=00 E0 86 93 0B 00 6F 0D 00 00 00]

2019-01-04 17:40:14.194 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+SENDMCASTB:0B,1F,FFFF,00,00,0000,0000

à†“

o

2019-01-04 17:40:14.243 [DEBUG] [zigbee.transaction.ZigBeeTransaction] - Transaction timeout: SimpleDescriptorRequest [0/0 -> 14237/0, cluster=0004, TID=D2, nwkAddrOfInterest=14237, endpoint=1]

2019-01-04 17:40:14.245 [DEBUG] [iscovery.ZigBeeNodeServiceDiscoverer] - 000D6F000B9386E0: Node SVC Discovery: SimpleDescriptorResponse returned null

2019-01-04 17:40:14.247 [DEBUG] [zigbee.transaction.ZigBeeTransaction] - Transaction timeout: SimpleDescriptorRequest [0/0 -> 14237/0, cluster=0004, TID=D3, nwkAddrOfInterest=14237, endpoint=1]

2019-01-04 17:40:14.247 [DEBUG] [iscovery.ZigBeeNodeServiceDiscoverer] - 000D6F000B9386E0: Node SVC Discovery: request ACTIVE_ENDPOINTS failed. Retry 1, wait 2104ms before retry.

2019-01-04 17:40:14.249 [DEBUG] [iscovery.ZigBeeNodeServiceDiscoverer] - 000D6F000B9386E0: Node SVC Discovery: SimpleDescriptorResponse returned null

2019-01-04 17:40:14.251 [DEBUG] [iscovery.ZigBeeNodeServiceDiscoverer] - 000D6F000B9386E0: Node SVC Discovery: request ACTIVE_ENDPOINTS failed. Retry 1, wait 2104ms before retry.

2019-01-04 17:40:14.353 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK

2019-01-04 17:40:14.355 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisSendMulticastCommand [radius=31, address=65535, sourceEp=0, destEp=0, profileId=0, clusterId=0, messageData=00 E0 86 93 0B 00 6F 0D 00 00 00, status=SUCCESS]

2019-01-04 17:40:14.360 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:RX:0000,0000,00,00,8000,0C:à†“

o

¢,00,FF

2019-01-04 17:40:14.361 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisReceiveMessageEvent [ieeeAddress=null, networkAddress=0, profileId=0, destinationEp=0, sourceEp=0, clusterId=32768, messageData=00 00 E0 86 93 0B 00 6F 0D 00 0C A2, rssi=0, lqi=255]

2019-01-04 17:40:14.363 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=32768, addressMode=null, radius=0, apsSecurity=false, apsCounter=0, payload=00 00 E0 86 93 0B 00 6F 0D 00 0C A2]

2019-01-04 17:40:14.366 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: NetworkAddressResponse [0/0 -> 0/0, cluster=8000, TID=NULL, status=SUCCESS, ieeeAddrRemoteDev=000D6F000B9386E0, nwkAddrRemoteDev=41484, startIndex=null, nwkAddrAssocDevList=[]]

2019-01-04 17:40:14.368 [DEBUG] [zigbee.transaction.ZigBeeTransaction] - Transaction complete: NetworkAddressRequest [0/0 -> 65533/0, cluster=0000, TID=DA, ieeeAddr=000D6F000B9386E0, requestType=0, startIndex=0]

2019-01-04 17:40:14.370 [DEBUG] [pp.discovery.ZigBeeNetworkDiscoverer] - 41484: NWK Discovery scheduling node discovery

2019-01-04 17:40:14.374 [DEBUG] [zigbee.transaction.ZigBeeTransaction] - Transaction complete: NetworkAddressRequest [0/0 -> 65535/0, cluster=0000, TID=DB, ieeeAddr=000D6F000B9386E0, requestType=0, startIndex=0]

2019-01-04 17:40:14.376 [DEBUG] [pp.discovery.ZigBeeNetworkDiscoverer] - 000D6F000B9386E0: NWK Discovery finishing node rediscovery

2019-01-04 17:40:14.377 [DEBUG] [iscovery.ZigBeeNodeServiceDiscoverer] - 41484: Node SVC Discovery: NetworkAddressRequest returned NetworkAddressResponse [0/0 -> 0/0, cluster=8000, TID=NULL, status=SUCCESS, ieeeAddrRemoteDev=000D6F000B9386E0, nwkAddrRemoteDev=41484, startIndex=null, nwkAddrAssocDevList=[]]

2019-01-04 17:40:14.377 [DEBUG] [pp.discovery.ZigBeeNetworkDiscoverer] - 41484: NWK Discovery starting node discovery

2019-01-04 17:40:14.379 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: IeeeAddressRequest [0/0 -> 41484/0, cluster=0001, TID=DC, nwkAddrOfInterest=41484, requestType=1, startIndex=0]

2019-01-04 17:40:14.380 [DEBUG] [iscovery.ZigBeeNodeServiceDiscoverer] - 000D6F000B9386E0: Node SVC Discovery: request NWK_ADDRESS successful. Advanced to ACTIVE_ENDPOINTS.

2019-01-04 17:40:14.381 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=41484/0, profile=0000, cluster=1, addressMode=DEVICE, radius=31, apsSecurity=false, apsCounter=220, payload=00 0C A2 01 00]

2019-01-04 17:40:14.382 [DEBUG] [iscovery.ZigBeeNodeServiceDiscoverer] - 000D6F000B9386E0: Node SVC Discovery: running

2019-01-04 17:40:14.383 [DEBUG] [ngle.telegesis.ZigBeeDongleTelegesis] - Telegesis send: TelegesisSendUnicastCommand [address=41484, sourceEp=0, destEp=0, profileId=0, clusterId=1, messageData=00 0C A2 01 00, messageId=null]

2019-01-04 17:40:14.385 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ActiveEndpointsRequest [0/0 -> 41484/0, cluster=0005, TID=DD, nwkAddrOfInterest=41484]

2019-01-04 17:40:14.385 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1

2019-01-04 17:40:14.386 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisSendUnicastCommand [address=41484, sourceEp=0, destEp=0, profileId=0, clusterId=1, messageData=00 0C A2 01 00, messageId=null]

2019-01-04 17:40:14.387 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=41484/0, profile=0000, cluster=5, addressMode=DEVICE, radius=31, apsSecurity=false, apsCounter=221, payload=00 0C A2]

2019-01-04 17:40:14.389 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+SENDUCASTB:05,A20C,00,00,0000,0001

¢


2019-01-04 17:40:14.390 [DEBUG] [ngle.telegesis.ZigBeeDongleTelegesis] - Telegesis send: TelegesisSendUnicastCommand [address=41484, sourceEp=0, destEp=0, profileId=0, clusterId=5, messageData=00 0C A2, messageId=null]

2019-01-04 17:40:14.392 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1

2019-01-04 17:40:14.558 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:SEQ:94

2019-01-04 17:40:14.561 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK

2019-01-04 17:40:14.563 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisSendUnicastCommand [address=41484, sourceEp=0, destEp=0, profileId=0, clusterId=1, messageData=00 0C A2 01 00, messageId=148, status=SUCCESS]

2019-01-04 17:40:14.566 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisSendUnicastCommand [address=41484, sourceEp=0, destEp=0, profileId=0, clusterId=5, messageData=00 0C A2, messageId=null]

2019-01-04 17:40:14.570 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+SENDUCASTB:03,A20C,00,00,0000,0005

¢

2019-01-04 17:40:14.574 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:ACK:94

2019-01-04 17:40:14.576 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisAckMessageEvent [messageId=148]

2019-01-04 17:40:14.578 [DEBUG] [ngle.telegesis.ZigBeeDongleTelegesis] - Unhandled Telegesis Frame: TelegesisAckMessageEvent [messageId=148]

2019-01-04 17:40:14.585 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:RX:0000,0000,00,00,8001,0D:à†“

o

¢,00,FF

2019-01-04 17:40:14.588 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisReceiveMessageEvent [ieeeAddress=null, networkAddress=0, profileId=0, destinationEp=0, sourceEp=0, clusterId=32769, messageData=00 00 E0 86 93 0B 00 6F 0D 00 0C A2 00, rssi=0, lqi=255]

2019-01-04 17:40:14.591 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=32769, addressMode=null, radius=0, apsSecurity=false, apsCounter=0, payload=00 00 E0 86 93 0B 00 6F 0D 00 0C A2 00]

2019-01-04 17:40:14.594 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: IeeeAddressResponse [0/0 -> 0/0, cluster=8001, TID=NULL, status=SUCCESS, ieeeAddrRemoteDev=000D6F000B9386E0, nwkAddrRemoteDev=41484, startIndex=null, nwkAddrAssocDevList=[]]

2019-01-04 17:40:14.601 [DEBUG] [zigbee.transaction.ZigBeeTransaction] - Transaction complete: IeeeAddressRequest [0/0 -> 41484/0, cluster=0001, TID=DC, nwkAddrOfInterest=41484, requestType=1, startIndex=0]

2019-01-04 17:40:14.610 [DEBUG] [pp.discovery.ZigBeeNetworkDiscoverer] - 41484: NWK Discovery IeeeAddressRequest returned IeeeAddressResponse [0/0 -> 0/0, cluster=8001, TID=NULL, status=SUCCESS, ieeeAddrRemoteDev=000D6F000B9386E0, nwkAddrRemoteDev=41484, startIndex=null, nwkAddrAssocDevList=[]]

2019-01-04 17:40:14.613 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - 000D6F000B9386E0: Node 41484 update

2019-01-04 17:40:14.617 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F000B9386E0: Node updated - ZigBeeNode [IEEE=000D6F000B9386E0, NWK=A20C, Type=UNKNOWN]

2019-01-04 17:40:14.629 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Start.

2019-01-04 17:40:14.631 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F000B9386E0: Node has not finished discovery

2019-01-04 17:40:14.634 [INFO ] [bee.discovery.ZigBeeDiscoveryService] - 000D6F000B9386E0: Starting ZigBee device discovery

2019-01-04 17:40:14.639 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - 000D6F000B9386E0: Creating ZigBee device zigbee:device with bridge zigbee:coordinator_telegesis:04000B3A

2019-01-04 17:40:14.643 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - 000D6F000B9386E0: Node discovery not complete

2019-01-04 17:40:14.650 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Done.

2019-01-04 17:40:14.653 [DEBUG] [pp.discovery.ZigBeeNetworkDiscoverer] - 41484: NWK Discovery ending node discovery

2019-01-04 17:40:14.720 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:RX:A20C,0000,00,00,8000,0C:à†“

o

¢,-52,FF

2019-01-04 17:40:14.722 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisReceiveMessageEvent [ieeeAddress=null, networkAddress=41484, profileId=0, destinationEp=0, sourceEp=0, clusterId=32768, messageData=00 00 E0 86 93 0B 00 6F 0D 00 0C A2, rssi=-82, lqi=255]

2019-01-04 17:40:14.724 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=41484/0, destinationAddress=0/0, profile=0000, cluster=32768, addressMode=null, radius=0, apsSecurity=false, apsCounter=0, payload=00 00 E0 86 93 0B 00 6F 0D 00 0C A2]

2019-01-04 17:40:14.726 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: NetworkAddressResponse [41484/0 -> 0/0, cluster=8000, TID=NULL, status=SUCCESS, ieeeAddrRemoteDev=000D6F000B9386E0, nwkAddrRemoteDev=41484, startIndex=null, nwkAddrAssocDevList=[]]

2019-01-04 17:40:14.732 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:SEQ:96

2019-01-04 17:40:14.734 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK

2019-01-04 17:40:14.736 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisSendUnicastCommand [address=41484, sourceEp=0, destEp=0, profileId=0, clusterId=5, messageData=00 0C A2, messageId=150, status=SUCCESS]

2019-01-04 17:40:15.416 [DEBUG] [iscovery.ZigBeeNodeServiceDiscoverer] - 000D6F00108DEAEA: Node SVC Discovery: running

2019-01-04 17:40:15.420 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ManagementRoutingRequest [0/0 -> 0/0, cluster=0032, TID=DE, startIndex=0]

2019-01-04 17:40:15.423 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=50, addressMode=DEVICE, radius=31, apsSecurity=false, apsCounter=222, payload=00 00]

2019-01-04 17:40:15.427 [DEBUG] [ngle.telegesis.ZigBeeDongleTelegesis] - Telegesis send: TelegesisSendUnicastCommand [address=0, sourceEp=0, destEp=0, profileId=0, clusterId=50, messageData=00 00, messageId=null]

2019-01-04 17:40:15.429 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1

2019-01-04 17:40:15.432 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisSendUnicastCommand [address=0, sourceEp=0, destEp=0, profileId=0, clusterId=50, messageData=00 00, messageId=null]

2019-01-04 17:40:15.436 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+SENDUCASTB:02,0000,00,00,0000,0032

2019-01-04 17:40:15.729 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:SEQ:97

2019-01-04 17:40:15.732 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK

2019-01-04 17:40:15.734 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisSendUnicastCommand [address=0, sourceEp=0, destEp=0, profileId=0, clusterId=50, messageData=00 00, messageId=151, status=SUCCESS]

2019-01-04 17:40:15.745 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:RX:0000,0000,00,00,8032,50:@7
,00,FF

2019-01-04 17:40:15.750 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisReceiveMessageEvent [ieeeAddress=null, networkAddress=0, profileId=0, destinationEp=0, sourceEp=0, clusterId=32818, messageData=00 00 40 00 0F 9D 37 01 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00, rssi=0, lqi=255]

2019-01-04 17:40:15.754 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=32818, addressMode=null, radius=0, apsSecurity=false, apsCounter=0, payload=00 00 40 00 0F 9D 37 01 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00]

2019-01-04 17:40:15.758 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ManagementRoutingResponse [0/0 -> 0/0, cluster=8032, TID=NULL, status=SUCCESS, routingTableEntries=64, startIndex=0, routingTableList=[RoutingTable [destinationAddress=14237, status=DISCOVERY_UNDERWAY, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0]]]

2019-01-04 17:40:15.769 [DEBUG] [zigbee.transaction.ZigBeeTransaction] - Transaction complete: ManagementRoutingRequest [0/0 -> 0/0, cluster=0032, TID=DE, startIndex=0]

2019-01-04 17:40:15.772 [DEBUG] [iscovery.ZigBeeNodeServiceDiscoverer] - 000D6F00108DEAEA: Node SVC Discovery: ManagementRoutingRequest returned CommandResult [SUCCESS, ManagementRoutingResponse [0/0 -> 0/0, cluster=8032, TID=NULL, status=SUCCESS, routingTableEntries=64, startIndex=0, routingTableList=[RoutingTable [destinationAddress=14237, status=DISCOVERY_UNDERWAY, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0]]]]

2019-01-04 17:40:15.773 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:ACK:97

2019-01-04 17:40:15.775 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisAckMessageEvent [messageId=151]

2019-01-04 17:40:15.776 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ManagementRoutingRequest [0/0 -> 0/0, cluster=0032, TID=DF, startIndex=15]

2019-01-04 17:40:15.777 [DEBUG] [ngle.telegesis.ZigBeeDongleTelegesis] - Unhandled Telegesis Frame: TelegesisAckMessageEvent [messageId=151]

2019-01-04 17:40:15.779 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=50, addressMode=DEVICE, radius=31, apsSecurity=false, apsCounter=223, payload=00 0F]

2019-01-04 17:40:15.783 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:RX:A20C,0000,00,00,8005,07:

¢
,-52,FF

2019-01-04 17:40:15.782 [DEBUG] [ngle.telegesis.ZigBeeDongleTelegesis] - Telegesis send: TelegesisSendUnicastCommand [address=0, sourceEp=0, destEp=0, profileId=0, clusterId=50, messageData=00 0F, messageId=null]

2019-01-04 17:40:15.785 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisReceiveMessageEvent [ieeeAddress=null, networkAddress=41484, profileId=0, destinationEp=0, sourceEp=0, clusterId=32773, messageData=00 00 0C A2 02 01 02, rssi=-82, lqi=255]

2019-01-04 17:40:15.785 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1

2019-01-04 17:40:15.788 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=41484/0, destinationAddress=0/0, profile=0000, cluster=32773, addressMode=null, radius=0, apsSecurity=false, apsCounter=0, payload=00 00 0C A2 02 01 02]

2019-01-04 17:40:15.788 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisSendUnicastCommand [address=0, sourceEp=0, destEp=0, profileId=0, clusterId=50, messageData=00 0F, messageId=null]

2019-01-04 17:40:15.791 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ActiveEndpointsResponse [41484/0 -> 0/0, cluster=8005, TID=NULL, status=SUCCESS, nwkAddrOfInterest=41484, activeEpList=[1, 2]]

2019-01-04 17:40:15.798 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+SENDUCASTB:02,0000,00,00,0000,0032



2019-01-04 17:40:15.800 [DEBUG] [zigbee.transaction.ZigBeeTransaction] - Transaction complete: ActiveEndpointsRequest [0/0 -> 41484/0, cluster=0005, TID=DD, nwkAddrOfInterest=41484]

2019-01-04 17:40:15.804 [DEBUG] [iscovery.ZigBeeNodeServiceDiscoverer] - 000D6F000B9386E0: Node SVC Discovery: ActiveEndpointsResponse returned CommandResult [SUCCESS, ActiveEndpointsResponse [41484/0 -> 0/0, cluster=8005, TID=NULL, status=SUCCESS, nwkAddrOfInterest=41484, activeEpList=[1, 2]]]

2019-01-04 17:40:15.806 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: SimpleDescriptorRequest [0/0 -> 41484/0, cluster=0004, TID=E0, nwkAddrOfInterest=41484, endpoint=1]

2019-01-04 17:40:15.806 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:ACK:96

2019-01-04 17:40:15.809 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisAckMessageEvent [messageId=150]

2019-01-04 17:40:15.809 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=41484/0, profile=0000, cluster=4, addressMode=DEVICE, radius=31, apsSecurity=false, apsCounter=224, payload=00 0C A2 01]

2019-01-04 17:40:15.811 [DEBUG] [ngle.telegesis.ZigBeeDongleTelegesis] - Telegesis send: TelegesisSendUnicastCommand [address=41484, sourceEp=0, destEp=0, profileId=0, clusterId=4, messageData=00 0C A2 01, messageId=null]

2019-01-04 17:40:15.811 [DEBUG] [ngle.telegesis.ZigBeeDongleTelegesis] - Unhandled Telegesis Frame: TelegesisAckMessageEvent [messageId=150]

2019-01-04 17:40:15.814 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1

2019-01-04 17:40:15.856 [DEBUG] [iscovery.ZigBeeNodeServiceDiscoverer] - 000D6F000B9386E0: Node SVC Discovery: running

2019-01-04 17:40:15.860 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ActiveEndpointsRequest [0/0 -> 41484/0, cluster=0005, TID=E1, nwkAddrOfInterest=41484]

2019-01-04 17:40:15.864 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=41484/0, profile=0000, cluster=5, addressMode=DEVICE, radius=31, apsSecurity=false, apsCounter=225, payload=00 0C A2]

2019-01-04 17:40:15.868 [DEBUG] [ngle.telegesis.ZigBeeDongleTelegesis] - Telegesis send: TelegesisSendUnicastCommand [address=41484, sourceEp=0, destEp=0, profileId=0, clusterId=5, messageData=00 0C A2, messageId=null]

2019-01-04 17:40:15.871 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 2

2019-01-04 17:40:15.996 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:SEQ:99

2019-01-04 17:40:15.999 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK

2019-01-04 17:40:16.001 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisSendUnicastCommand [address=0, sourceEp=0, destEp=0, profileId=0, clusterId=50, messageData=00 0F, messageId=153, status=SUCCESS]

2019-01-04 17:40:16.003 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisSendUnicastCommand [address=41484, sourceEp=0, destEp=0, profileId=0, clusterId=4, messageData=00 0C A2 01, messageId=null]

2019-01-04 17:40:16.007 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+SENDUCASTB:04,A20C,00,00,0000,0004

¢


2019-01-04 17:40:16.013 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:RX:0000,0000,00,00,8032,50:@,00,FF

2019-01-04 17:40:16.017 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisReceiveMessageEvent [ieeeAddress=null, networkAddress=0, profileId=0, destinationEp=0, sourceEp=0, clusterId=32818, messageData=00 00 40 0F 0F 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00, rssi=0, lqi=255]

2019-01-04 17:40:16.020 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=32818, addressMode=null, radius=0, apsSecurity=false, apsCounter=0, payload=00 00 40 0F 0F 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00]

2019-01-04 17:40:16.023 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ManagementRoutingResponse [0/0 -> 0/0, cluster=8032, TID=NULL, status=SUCCESS, routingTableEntries=64, startIndex=15, routingTableList=[RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0]]]

2019-01-04 17:40:16.026 [DEBUG] [zigbee.transaction.ZigBeeTransaction] - Transaction complete: ManagementRoutingRequest [0/0 -> 0/0, cluster=0032, TID=DF, startIndex=15]

2019-01-04 17:40:16.028 [DEBUG] [iscovery.ZigBeeNodeServiceDiscoverer] - 000D6F00108DEAEA: Node SVC Discovery: ManagementRoutingRequest returned CommandResult [SUCCESS, ManagementRoutingResponse [0/0 -> 0/0, cluster=8032, TID=NULL, status=SUCCESS, routingTableEntries=64, startIndex=15, routingTableList=[RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0]]]]

2019-01-04 17:40:16.034 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:ACK:99

2019-01-04 17:40:16.035 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisAckMessageEvent [messageId=153]

2019-01-04 17:40:16.036 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ManagementRoutingRequest [0/0 -> 0/0, cluster=0032, TID=E2, startIndex=30]

2019-01-04 17:40:16.037 [DEBUG] [ngle.telegesis.ZigBeeDongleTelegesis] - Unhandled Telegesis Frame: TelegesisAckMessageEvent [messageId=153]

2019-01-04 17:40:16.039 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=50, addressMode=DEVICE, radius=31, apsSecurity=false, apsCounter=226, payload=00 1E]

2019-01-04 17:40:16.042 [DEBUG] [ngle.telegesis.ZigBeeDongleTelegesis] - Telegesis send: TelegesisSendUnicastCommand [address=0, sourceEp=0, destEp=0, profileId=0, clusterId=50, messageData=00 1E, messageId=null]

2019-01-04 17:40:16.044 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 2

2019-01-04 17:40:16.141 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:SEQ:9B

2019-01-04 17:40:16.143 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK

2019-01-04 17:40:16.144 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisSendUnicastCommand [address=41484, sourceEp=0, destEp=0, profileId=0, clusterId=4, messageData=00 0C A2 01, messageId=155, status=SUCCESS]

2019-01-04 17:40:16.146 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisSendUnicastCommand [address=41484, sourceEp=0, destEp=0, profileId=0, clusterId=5, messageData=00 0C A2, messageId=null]

2019-01-04 17:40:16.149 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+SENDUCASTB:03,A20C,00,00,0000,0005

¢

2019-01-04 17:40:16.282 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:SEQ:9C

2019-01-04 17:40:16.284 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK

2019-01-04 17:40:16.285 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisSendUnicastCommand [address=41484, sourceEp=0, destEp=0, profileId=0, clusterId=5, messageData=00 0C A2, messageId=156, status=SUCCESS]

2019-01-04 17:40:16.287 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisSendUnicastCommand [address=0, sourceEp=0, destEp=0, profileId=0, clusterId=50, messageData=00 1E, messageId=null]

2019-01-04 17:40:16.290 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+SENDUCASTB:02,0000,00,00,0000,0032



2019-01-04 17:40:16.357 [DEBUG] [iscovery.ZigBeeNodeServiceDiscoverer] - 000D6F000B9386E0: Node SVC Discovery: running

2019-01-04 17:40:16.359 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ActiveEndpointsRequest [0/0 -> 14237/0, cluster=0005, TID=E3, nwkAddrOfInterest=14237]

2019-01-04 17:40:16.361 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=14237/0, profile=0000, cluster=5, addressMode=DEVICE, radius=31, apsSecurity=false, apsCounter=227, payload=00 9D 37]

2019-01-04 17:40:16.363 [DEBUG] [ngle.telegesis.ZigBeeDongleTelegesis] - Telegesis send: TelegesisSendUnicastCommand [address=14237, sourceEp=0, destEp=0, profileId=0, clusterId=5, messageData=00 9D 37, messageId=null]

2019-01-04 17:40:16.364 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1

2019-01-04 17:40:16.491 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:SEQ:9D

2019-01-04 17:40:16.493 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK

2019-01-04 17:40:16.494 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisSendUnicastCommand [address=0, sourceEp=0, destEp=0, profileId=0, clusterId=50, messageData=00 1E, messageId=157, status=SUCCESS]

2019-01-04 17:40:16.496 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisSendUnicastCommand [address=14237, sourceEp=0, destEp=0, profileId=0, clusterId=5, messageData=00 9D 37, messageId=null]

2019-01-04 17:40:16.499 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+SENDUCASTB:03,379D,00,00,0000,0005

7

2019-01-04 17:40:16.505 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:RX:0000,0000,00,00,8032,50:@,00,FF

2019-01-04 17:40:16.507 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisReceiveMessageEvent [ieeeAddress=null, networkAddress=0, profileId=0, destinationEp=0, sourceEp=0, clusterId=32818, messageData=00 00 40 1E 0F 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00, rssi=0, lqi=255]

2019-01-04 17:40:16.510 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=32818, addressMode=null, radius=0, apsSecurity=false, apsCounter=0, payload=00 00 40 1E 0F 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00]

2019-01-04 17:40:16.512 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ManagementRoutingResponse [0/0 -> 0/0, cluster=8032, TID=NULL, status=SUCCESS, routingTableEntries=64, startIndex=30, routingTableList=[RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0]]]

2019-01-04 17:40:16.519 [DEBUG] [zigbee.transaction.ZigBeeTransaction] - Transaction complete: ManagementRoutingRequest [0/0 -> 0/0, cluster=0032, TID=E2, startIndex=30]

2019-01-04 17:40:16.521 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:ACK:9D

2019-01-04 17:40:16.523 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisAckMessageEvent [messageId=157]

2019-01-04 17:40:16.522 [DEBUG] [iscovery.ZigBeeNodeServiceDiscoverer] - 000D6F00108DEAEA: Node SVC Discovery: ManagementRoutingRequest returned CommandResult [SUCCESS, ManagementRoutingResponse [0/0 -> 0/0, cluster=8032, TID=NULL, status=SUCCESS, routingTableEntries=64, startIndex=30, routingTableList=[RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0]]]]

2019-01-04 17:40:16.524 [DEBUG] [ngle.telegesis.ZigBeeDongleTelegesis] - Unhandled Telegesis Frame: TelegesisAckMessageEvent [messageId=157]

2019-01-04 17:40:16.527 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ManagementRoutingRequest [0/0 -> 0/0, cluster=0032, TID=E4, startIndex=45]

2019-01-04 17:40:16.529 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=50, addressMode=DEVICE, radius=31, apsSecurity=false, apsCounter=228, payload=00 2D]

2019-01-04 17:40:16.530 [DEBUG] [ngle.telegesis.ZigBeeDongleTelegesis] - Telegesis send: TelegesisSendUnicastCommand [address=0, sourceEp=0, destEp=0, profileId=0, clusterId=50, messageData=00 2D, messageId=null]

2019-01-04 17:40:16.532 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1

2019-01-04 17:40:16.633 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:SEQ:9F

2019-01-04 17:40:16.635 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK

2019-01-04 17:40:16.638 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisSendUnicastCommand [address=14237, sourceEp=0, destEp=0, profileId=0, clusterId=5, messageData=00 9D 37, messageId=159, status=SUCCESS]

2019-01-04 17:40:16.640 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisSendUnicastCommand [address=0, sourceEp=0, destEp=0, profileId=0, clusterId=50, messageData=00 2D, messageId=null]

2019-01-04 17:40:16.645 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+SENDUCASTB:02,0000,00,00,0000,0032

-

2019-01-04 17:40:16.870 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:RX:A20C,0000,00,00,8004,1F:

¢


 

ü
,-53,FF

2019-01-04 17:40:16.874 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisReceiveMessageEvent [ieeeAddress=null, networkAddress=41484, profileId=0, destinationEp=0, sourceEp=0, clusterId=32772, messageData=00 00 0C A2 1A 01 04 01 02 04 00 08 00 00 01 00 03 00 20 00 02 04 00 05 05 0B 02 FC 01 19 00, rssi=-83, lqi=255]

2019-01-04 17:40:16.877 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=41484/0, destinationAddress=0/0, profile=0000, cluster=32772, addressMode=null, radius=0, apsSecurity=false, apsCounter=0, payload=00 00 0C A2 1A 01 04 01 02 04 00 08 00 00 01 00 03 00 20 00 02 04 00 05 05 0B 02 FC 01 19 00]

2019-01-04 17:40:16.880 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: SimpleDescriptorResponse [41484/0 -> 0/0, cluster=8004, TID=NULL, status=SUCCESS, nwkAddrOfInterest=41484, length=26, simpleDescriptor=SimpleDescriptor [endpoint=1, profileId=0104, deviceId=1026, deviceVersion=0, inputClusterList=[0, 1, 3, 32, 1026, 1280, 2821, 64514], outputClusterList=[25]]]

2019-01-04 17:40:16.886 [DEBUG] [zigbee.transaction.ZigBeeTransaction] - Transaction complete: SimpleDescriptorRequest [0/0 -> 41484/0, cluster=0004, TID=E0, nwkAddrOfInterest=41484, endpoint=1]

2019-01-04 17:40:16.890 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:ACK:9B

2019-01-04 17:40:16.891 [DEBUG] [iscovery.ZigBeeNodeServiceDiscoverer] - 000D6F000B9386E0: Node SVC Discovery: SimpleDescriptorResponse returned SimpleDescriptorResponse [41484/0 -> 0/0, cluster=8004, TID=NULL, status=SUCCESS, nwkAddrOfInterest=41484, length=26, simpleDescriptor=SimpleDescriptor [endpoint=1, profileId=0104, deviceId=1026, deviceVersion=0, inputClusterList=[0, 1, 3, 32, 1026, 1280, 2821, 64514], outputClusterList=[25]]]

2019-01-04 17:40:16.893 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisAckMessageEvent [messageId=155]

2019-01-04 17:40:16.894 [DEBUG] [.zsmartsystems.zigbee.ZigBeeEndpoint] - 41484/1: Setting input clusters [0, 1, 3, 32, 1026, 1280, 2821, 64514]

2019-01-04 17:40:16.897 [DEBUG] [ngle.telegesis.ZigBeeDongleTelegesis] - Unhandled Telegesis Frame: TelegesisAckMessageEvent [messageId=155]

2019-01-04 17:40:16.898 [DEBUG] [.zsmartsystems.zigbee.ZigBeeEndpoint] - 41484/1: Setting cluster BASIC as server

2019-01-04 17:40:16.902 [DEBUG] [.zsmartsystems.zigbee.ZigBeeEndpoint] - 41484/1: Setting cluster POWER_CONFIGURATION as server

2019-01-04 17:40:16.904 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:RX:A20C,0000,00,00,8005,07:

¢
,-53,FF

2019-01-04 17:40:16.907 [DEBUG] [.zsmartsystems.zigbee.ZigBeeEndpoint] - 41484/1: Setting cluster IDENTIFY as server

2019-01-04 17:40:16.909 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisReceiveMessageEvent [ieeeAddress=null, networkAddress=41484, profileId=0, destinationEp=0, sourceEp=0, clusterId=32773, messageData=00 00 0C A2 02 01 02, rssi=-83, lqi=255]

2019-01-04 17:40:16.911 [DEBUG] [.zsmartsystems.zigbee.ZigBeeEndpoint] - 41484/1: Setting cluster POLL_CONTROL as server

2019-01-04 17:40:16.912 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=41484/0, destinationAddress=0/0, profile=0000, cluster=32773, addressMode=null, radius=0, apsSecurity=false, apsCounter=0, payload=00 00 0C A2 02 01 02]

2019-01-04 17:40:16.915 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ActiveEndpointsResponse [41484/0 -> 0/0, cluster=8005, TID=NULL, status=SUCCESS, nwkAddrOfInterest=41484, activeEpList=[1, 2]]

2019-01-04 17:40:16.915 [DEBUG] [.zsmartsystems.zigbee.ZigBeeEndpoint] - 41484/1: Setting cluster TEMPERATURE_MEASUREMENT as server

2019-01-04 17:40:16.919 [DEBUG] [zigbee.transaction.ZigBeeTransaction] - Transaction complete: ActiveEndpointsRequest [0/0 -> 41484/0, cluster=0005, TID=E1, nwkAddrOfInterest=41484]

2019-01-04 17:40:16.919 [DEBUG] [.zsmartsystems.zigbee.ZigBeeEndpoint] - 41484/1: Setting cluster IAS_ZONE as server

2019-01-04 17:40:16.925 [DEBUG] [.zsmartsystems.zigbee.ZigBeeEndpoint] - 41484/1: Setting cluster DIAGNOSTICS as server

2019-01-04 17:40:16.924 [DEBUG] [iscovery.ZigBeeNodeServiceDiscoverer] - 000D6F000B9386E0: Node SVC Discovery: ActiveEndpointsResponse returned CommandResult [SUCCESS, ActiveEndpointsResponse [41484/0 -> 0/0, cluster=8005, TID=NULL, status=SUCCESS, nwkAddrOfInterest=41484, activeEpList=[1, 2]]]

2019-01-04 17:40:16.927 [DEBUG] [.zsmartsystems.zigbee.ZigBeeEndpoint] - 41484/1: Unsupported cluster 64514

2019-01-04 17:40:16.928 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: SimpleDescriptorRequest [0/0 -> 41484/0, cluster=0004, TID=E5, nwkAddrOfInterest=41484, endpoint=1]

2019-01-04 17:40:16.931 [DEBUG] [.zsmartsystems.zigbee.ZigBeeEndpoint] - 41484/1: Setting output clusters [25]

2019-01-04 17:40:16.931 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=41484/0, profile=0000, cluster=4, addressMode=DEVICE, radius=31, apsSecurity=false, apsCounter=229, payload=00 0C A2 01]

2019-01-04 17:40:16.933 [DEBUG] [ngle.telegesis.ZigBeeDongleTelegesis] - Telegesis send: TelegesisSendUnicastCommand [address=41484, sourceEp=0, destEp=0, profileId=0, clusterId=4, messageData=00 0C A2 01, messageId=null]

2019-01-04 17:40:16.933 [DEBUG] [.zsmartsystems.zigbee.ZigBeeEndpoint] - 41484/1: Setting cluster OTA_UPGRADE as client

2019-01-04 17:40:16.932 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:ACK:9C

2019-01-04 17:40:16.935 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisAckMessageEvent [messageId=156]

2019-01-04 17:40:16.935 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: SimpleDescriptorRequest [0/0 -> 41484/0, cluster=0004, TID=E6, nwkAddrOfInterest=41484, endpoint=2]

2019-01-04 17:40:16.936 [DEBUG] [ngle.telegesis.ZigBeeDongleTelegesis] - Unhandled Telegesis Frame: TelegesisAckMessageEvent [messageId=156]

2019-01-04 17:40:16.935 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1

2019-01-04 17:40:16.937 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=41484/0, profile=0000, cluster=4, addressMode=DEVICE, radius=31, apsSecurity=false, apsCounter=230, payload=00 0C A2 02]

2019-01-04 17:40:16.939 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:SEQ:A0

2019-01-04 17:40:16.939 [DEBUG] [ngle.telegesis.ZigBeeDongleTelegesis] - Telegesis send: TelegesisSendUnicastCommand [address=41484, sourceEp=0, destEp=0, profileId=0, clusterId=4, messageData=00 0C A2 02, messageId=null]

2019-01-04 17:40:16.941 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 2

2019-01-04 17:40:16.941 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK

2019-01-04 17:40:16.942 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisSendUnicastCommand [address=0, sourceEp=0, destEp=0, profileId=0, clusterId=50, messageData=00 2D, messageId=160, status=SUCCESS]

2019-01-04 17:40:16.944 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisSendUnicastCommand [address=41484, sourceEp=0, destEp=0, profileId=0, clusterId=4, messageData=00 0C A2 01, messageId=null]

2019-01-04 17:40:16.948 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+SENDUCASTB:04,A20C,00,00,0000,0004

¢


2019-01-04 17:40:16.954 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:RX:0000,0000,00,00,8032,50:@-,00,FF

2019-01-04 17:40:16.957 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisReceiveMessageEvent [ieeeAddress=null, networkAddress=0, profileId=0, destinationEp=0, sourceEp=0, clusterId=32818, messageData=00 00 40 2D 0F 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00, rssi=0, lqi=255]

2019-01-04 17:40:16.959 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=32818, addressMode=null, radius=0, apsSecurity=false, apsCounter=0, payload=00 00 40 2D 0F 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00]

2019-01-04 17:40:16.962 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ManagementRoutingResponse [0/0 -> 0/0, cluster=8032, TID=NULL, status=SUCCESS, routingTableEntries=64, startIndex=45, routingTableList=[RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0]]]

2019-01-04 17:40:16.965 [DEBUG] [zigbee.transaction.ZigBeeTransaction] - Transaction complete: ManagementRoutingRequest [0/0 -> 0/0, cluster=0032, TID=E4, startIndex=45]

2019-01-04 17:40:16.967 [DEBUG] [iscovery.ZigBeeNodeServiceDiscoverer] - 000D6F00108DEAEA: Node SVC Discovery: ManagementRoutingRequest returned CommandResult [SUCCESS, ManagementRoutingResponse [0/0 -> 0/0, cluster=8032, TID=NULL, status=SUCCESS, routingTableEntries=64, startIndex=45, routingTableList=[RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0]]]]

2019-01-04 17:40:16.971 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:ACK:A0

2019-01-04 17:40:16.971 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ManagementRoutingRequest [0/0 -> 0/0, cluster=0032, TID=E7, startIndex=60]

2019-01-04 17:40:16.973 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisAckMessageEvent [messageId=160]

2019-01-04 17:40:16.973 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=50, addressMode=DEVICE, radius=31, apsSecurity=false, apsCounter=231, payload=00 3C]

2019-01-04 17:40:16.974 [DEBUG] [ngle.telegesis.ZigBeeDongleTelegesis] - Unhandled Telegesis Frame: TelegesisAckMessageEvent [messageId=160]

2019-01-04 17:40:16.974 [DEBUG] [ngle.telegesis.ZigBeeDongleTelegesis] - Telegesis send: TelegesisSendUnicastCommand [address=0, sourceEp=0, destEp=0, profileId=0, clusterId=50, messageData=00 3C, messageId=null]

2019-01-04 17:40:16.975 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 2

2019-01-04 17:40:17.081 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:SEQ:A2

2019-01-04 17:40:17.083 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK

2019-01-04 17:40:17.084 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisSendUnicastCommand [address=41484, sourceEp=0, destEp=0, profileId=0, clusterId=4, messageData=00 0C A2 01, messageId=162, status=SUCCESS]

2019-01-04 17:40:17.086 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisSendUnicastCommand [address=41484, sourceEp=0, destEp=0, profileId=0, clusterId=4, messageData=00 0C A2 02, messageId=null]

2019-01-04 17:40:17.089 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+SENDUCASTB:04,A20C,00,00,0000,0004

¢

2019-01-04 17:40:17.223 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:SEQ:A3

2019-01-04 17:40:17.225 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK

2019-01-04 17:40:17.227 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisSendUnicastCommand [address=41484, sourceEp=0, destEp=0, profileId=0, clusterId=4, messageData=00 0C A2 02, messageId=163, status=SUCCESS]

2019-01-04 17:40:17.228 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisSendUnicastCommand [address=0, sourceEp=0, destEp=0, profileId=0, clusterId=50, messageData=00 3C, messageId=null]

2019-01-04 17:40:17.231 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+SENDUCASTB:02,0000,00,00,0000,0032

<

2019-01-04 17:40:17.403 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:SEQ:A4

2019-01-04 17:40:17.406 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK

2019-01-04 17:40:17.408 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisSendUnicastCommand [address=0, sourceEp=0, destEp=0, profileId=0, clusterId=50, messageData=00 3C, messageId=164, status=SUCCESS]

2019-01-04 17:40:17.413 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:RX:0000,0000,00,00,8032,19:@<,00,FF

2019-01-04 17:40:17.414 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisReceiveMessageEvent [ieeeAddress=null, networkAddress=0, profileId=0, destinationEp=0, sourceEp=0, clusterId=32818, messageData=00 00 40 3C 04 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00, rssi=0, lqi=255]

2019-01-04 17:40:17.416 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=32818, addressMode=null, radius=0, apsSecurity=false, apsCounter=0, payload=00 00 40 3C 04 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00]

2019-01-04 17:40:17.418 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ManagementRoutingResponse [0/0 -> 0/0, cluster=8032, TID=NULL, status=SUCCESS, routingTableEntries=64, startIndex=60, routingTableList=[RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0]]]

2019-01-04 17:40:17.424 [DEBUG] [zigbee.transaction.ZigBeeTransaction] - Transaction complete: ManagementRoutingRequest [0/0 -> 0/0, cluster=0032, TID=E7, startIndex=60]

2019-01-04 17:40:17.426 [DEBUG] [iscovery.ZigBeeNodeServiceDiscoverer] - 000D6F00108DEAEA: Node SVC Discovery: ManagementRoutingRequest returned CommandResult [SUCCESS, ManagementRoutingResponse [0/0 -> 0/0, cluster=8032, TID=NULL, status=SUCCESS, routingTableEntries=64, startIndex=60, routingTableList=[RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0]]]]

2019-01-04 17:40:17.427 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:ACK:A4

2019-01-04 17:40:17.427 [DEBUG] [iscovery.ZigBeeNodeServiceDiscoverer] - 000D6F00108DEAEA: Node SVC Discovery: ManagementLqiRequest complete [2 routes]

2019-01-04 17:40:17.429 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisAckMessageEvent [messageId=164]

2019-01-04 17:40:17.429 [DEBUG] [com.zsmartsystems.zigbee.ZigBeeNode ] - 000D6F00108DEAEA: Routing table NEW: [RoutingTable [destinationAddress=14237, status=DISCOVERY_UNDERWAY, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0]]

2019-01-04 17:40:17.430 [DEBUG] [com.zsmartsystems.zigbee.ZigBeeNode ] - 000D6F00108DEAEA: Routing table OLD: [RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0]]

2019-01-04 17:40:17.430 [DEBUG] [ngle.telegesis.ZigBeeDongleTelegesis] - Unhandled Telegesis Frame: TelegesisAckMessageEvent [messageId=164]

2019-01-04 17:40:17.432 [DEBUG] [com.zsmartsystems.zigbee.ZigBeeNode ] - 000D6F00108DEAEA: Routing table updated: [RoutingTable [destinationAddress=14237, status=DISCOVERY_UNDERWAY, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0]]

2019-01-04 17:40:17.433 [DEBUG] [iscovery.ZigBeeNodeServiceDiscoverer] - 000D6F00108DEAEA: Node SVC Discovery: request ROUTES successful. Advanced to NEIGHBORS.

2019-01-04 17:40:17.435 [DEBUG] [iscovery.ZigBeeNodeServiceDiscoverer] - 000D6F00108DEAEA: Node SVC Discovery: running

2019-01-04 17:40:17.437 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ManagementLqiRequest [0/0 -> 0/0, cluster=0031, TID=E8, startIndex=0]

2019-01-04 17:40:17.439 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=49, addressMode=DEVICE, radius=31, apsSecurity=false, apsCounter=232, payload=00 00]

2019-01-04 17:40:17.440 [DEBUG] [ngle.telegesis.ZigBeeDongleTelegesis] - Telegesis send: TelegesisSendUnicastCommand [address=0, sourceEp=0, destEp=0, profileId=0, clusterId=49, messageData=00 00, messageId=null]

2019-01-04 17:40:17.442 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1

2019-01-04 17:40:17.443 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisSendUnicastCommand [address=0, sourceEp=0, destEp=0, profileId=0, clusterId=49, messageData=00 00, messageId=null]

2019-01-04 17:40:17.446 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+SENDUCASTB:02,0000,00,00,0000,0031

2019-01-04 17:40:17.654 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:SEQ:A6

2019-01-04 17:40:17.658 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK

2019-01-04 17:40:17.660 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisSendUnicastCommand [address=0, sourceEp=0, destEp=0, profileId=0, clusterId=49, messageData=00 00, messageId=166, status=SUCCESS]

2019-01-04 17:40:17.669 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:RX:0000,0000,00,00,8031,1B:

ð$MÛý7à†“

o

¢
ÿ,00,FF

2019-01-04 17:40:17.672 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisReceiveMessageEvent [ieeeAddress=null, networkAddress=0, profileId=0, destinationEp=0, sourceEp=0, clusterId=32817, messageData=00 00 01 00 01 02 11 F0 24 4D DB FD 37 E0 86 93 0B 00 6F 0D 00 0C A2 12 00 01 FF, rssi=0, lqi=255]

2019-01-04 17:40:17.675 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=32817, addressMode=null, radius=0, apsSecurity=false, apsCounter=0, payload=00 00 01 00 01 02 11 F0 24 4D DB FD 37 E0 86 93 0B 00 6F 0D 00 0C A2 12 00 01 FF]

2019-01-04 17:40:17.679 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ManagementLqiResponse [0/0 -> 0/0, cluster=8031, TID=NULL, status=SUCCESS, neighborTableEntries=1, startIndex=0, neighborTableList=[NeighborTable [extendedPanId=37FDDB4D24F01102, extendedAddress=000D6F000B9386E0, networkAddress=41484, deviceType=END_DEVICE, rxOnWhenIdle=RX_OFF, relationship=CHILD, permitJoining=DISABLED, depth=1, lqi=255]]]

2019-01-04 17:40:17.686 [DEBUG] [zigbee.transaction.ZigBeeTransaction] - Transaction complete: ManagementLqiRequest [0/0 -> 0/0, cluster=0031, TID=E8, startIndex=0]

2019-01-04 17:40:17.689 [DEBUG] [iscovery.ZigBeeNodeServiceDiscoverer] - 000D6F00108DEAEA: Node SVC Discovery: ManagementLqiRequest response CommandResult [SUCCESS, ManagementLqiResponse [0/0 -> 0/0, cluster=8031, TID=NULL, status=SUCCESS, neighborTableEntries=1, startIndex=0, neighborTableList=[NeighborTable [extendedPanId=37FDDB4D24F01102, extendedAddress=000D6F000B9386E0, networkAddress=41484, deviceType=END_DEVICE, rxOnWhenIdle=RX_OFF, relationship=CHILD, permitJoining=DISABLED, depth=1, lqi=255]]]]

2019-01-04 17:40:17.691 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:ACK:A6

2019-01-04 17:40:17.692 [DEBUG] [iscovery.ZigBeeNodeServiceDiscoverer] - 000D6F00108DEAEA: Node SVC Discovery: ManagementLqiRequest complete [1 neighbors]

2019-01-04 17:40:17.693 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisAckMessageEvent [messageId=166]

2019-01-04 17:40:17.694 [DEBUG] [com.zsmartsystems.zigbee.ZigBeeNode ] - 000D6F00108DEAEA: Neighbor table updated: [NeighborTable [extendedPanId=37FDDB4D24F01102, extendedAddress=000D6F000B9386E0, networkAddress=41484, deviceType=END_DEVICE, rxOnWhenIdle=RX_OFF, relationship=CHILD, permitJoining=DISABLED, depth=1, lqi=255]]

2019-01-04 17:40:17.695 [DEBUG] [ngle.telegesis.ZigBeeDongleTelegesis] - Unhandled Telegesis Frame: TelegesisAckMessageEvent [messageId=166]

2019-01-04 17:40:17.696 [DEBUG] [iscovery.ZigBeeNodeServiceDiscoverer] - 000D6F00108DEAEA: Node SVC Discovery: request NEIGHBORS successful. Advanced to null.

2019-01-04 17:40:17.698 [DEBUG] [iscovery.ZigBeeNodeServiceDiscoverer] - 000D6F00108DEAEA: Node SVC Discovery: running

2019-01-04 17:40:17.701 [DEBUG] [iscovery.ZigBeeNodeServiceDiscoverer] - 000D6F00108DEAEA: Node SVC Discovery: complete

2019-01-04 17:40:17.701 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:NODELEFT:A20C,000D6F000B9386E0

2019-01-04 17:40:17.703 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - 000D6F00108DEAEA: Node 0 update

2019-01-04 17:40:17.705 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDeviceLeftNetworkEvent [networkAddress=41484, ieeeAddress=000D6F000B9386E0]

2019-01-04 17:40:17.709 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - 000D6F000B9386E0: nodeStatusUpdate - node status is DEVICE_LEFT, network address is 41484.

2019-01-04 17:40:17.712 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - 000D6F000B9386E0: Node 41484 is removed from the network

2019-01-04 17:40:17.723 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Start.

==> /var/log/openhab2/events.log <==

2019-01-04 17:40:17.727 [me.event.ThingUpdatedEvent] - Thing 'zigbee:coordinator_telegesis:04000B3A' has been updated.

==> /var/log/openhab2/openhab.log <==

2019-01-04 17:40:17.744 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Done.

2019-01-04 17:40:17.747 [DEBUG] [p.discovery.ZigBeeDiscoveryExtension] - DISCOVERY Extension: Removing discoverer for 000D6F000B9386E0

==> /var/log/openhab2/events.log <==

2019-01-04 17:40:17.753 [hingStatusInfoChangedEvent] - 'zigbee:device:04000B3A:000d6f000b9386e0' changed from OFFLINE: Node has not completed discovery to OFFLINE

==> /var/log/openhab2/openhab.log <==

2019-01-04 17:40:17.761 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Start.

2019-01-04 17:40:17.770 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Done.

2019-01-04 17:40:18.582 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:NACK:82

2019-01-04 17:40:18.585 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisNackMessageEvent [messageId=130]

2019-01-04 17:40:18.587 [DEBUG] [ngle.telegesis.ZigBeeDongleTelegesis] - Unhandled Telegesis Frame: TelegesisNackMessageEvent [messageId=130]

2019-01-04 17:40:18.730 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:NACK:83

2019-01-04 17:40:18.732 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisNackMessageEvent [messageId=131]

2019-01-04 17:40:18.734 [DEBUG] [ngle.telegesis.ZigBeeDongleTelegesis] - Unhandled Telegesis Frame: TelegesisNackMessageEvent [messageId=131]

2019-01-04 17:40:19.737 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1

2019-01-04 17:40:19.750 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisDisplayNetworkInformationCommand [device=null, channel=null, power=null, panId=null, epanId=null]

2019-01-04 17:40:19.753 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+N?

2019-01-04 17:40:19.886 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:+N=COO,11,-07,7661,37FDDB4D24F01102

2019-01-04 17:40:19.889 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK

2019-01-04 17:40:19.892 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDisplayNetworkInformationCommand [device=COO, channel=11, power=-7, panId=30305, epanId=37FDDB4D24F01102, status=SUCCESS]

2019-01-04 17:40:20.894 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1

2019-01-04 17:40:20.896 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisDisplayNetworkInformationCommand [device=null, channel=null, power=null, panId=null, epanId=null]

2019-01-04 17:40:20.900 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+N?

2019-01-04 17:40:21.033 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:+N=COO,11,-07,7661,37FDDB4D24F01102

2019-01-04 17:40:21.036 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK

2019-01-04 17:40:21.039 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDisplayNetworkInformationCommand [device=COO, channel=11, power=-7, panId=30305, epanId=37FDDB4D24F01102, status=SUCCESS]

2019-01-04 17:40:21.319 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:NACK:9F

2019-01-04 17:40:21.321 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisNackMessageEvent [messageId=159]

2019-01-04 17:40:21.324 [DEBUG] [ngle.telegesis.ZigBeeDongleTelegesis] - Unhandled Telegesis Frame: TelegesisNackMessageEvent [messageId=159]

2019-01-04 17:40:22.326 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1

2019-01-04 17:40:22.329 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisDisplayNetworkInformationCommand [device=null, channel=null, power=null, panId=null, epanId=null]

2019-01-04 17:40:22.332 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+N?

2019-01-04 17:40:22.465 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:+N=COO,11,-07,7661,37FDDB4D24F01102

2019-01-04 17:40:22.468 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK

2019-01-04 17:40:22.470 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDisplayNetworkInformationCommand [device=COO, channel=11, power=-7, panId=30305, epanId=37FDDB4D24F01102, status=SUCCESS]

2019-01-04 17:40:23.473 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1

2019-01-04 17:40:23.475 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisDisplayNetworkInformationCommand [device=null, channel=null, power=null, panId=null, epanId=null]

2019-01-04 17:40:23.479 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+N?

2019-01-04 17:40:23.612 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:+N=COO,11,-07,7661,37FDDB4D24F01102

2019-01-04 17:40:23.615 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK

2019-01-04 17:40:23.618 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDisplayNetworkInformationCommand [device=COO, channel=11, power=-7, panId=30305, epanId=37FDDB4D24F01102, status=SUCCESS]

2019-01-04 17:40:24.359 [DEBUG] [zigbee.transaction.ZigBeeTransaction] - Transaction timeout: ActiveEndpointsRequest [0/0 -> 14237/0, cluster=0005, TID=E3, nwkAddrOfInterest=14237]

2019-01-04 17:40:24.362 [DEBUG] [iscovery.ZigBeeNodeServiceDiscoverer] - 000D6F000B9386E0: Node SVC Discovery: ActiveEndpointsResponse returned CommandResult [TIMEOUT]

2019-01-04 17:40:24.364 [DEBUG] [iscovery.ZigBeeNodeServiceDiscoverer] - 000D6F000B9386E0: Node SVC Discovery: request ACTIVE_ENDPOINTS failed. Retry 2, wait 4208ms before retry.

2019-01-04 17:40:24.620 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1

2019-01-04 17:40:24.622 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisDisplayNetworkInformationCommand [device=null, channel=null, power=null, panId=null, epanId=null]

2019-01-04 17:40:24.626 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+N?

2019-01-04 17:40:24.759 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:+N=COO,11,-07,7661,37FDDB4D24F01102

2019-01-04 17:40:24.761 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK

2019-01-04 17:40:24.766 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDisplayNetworkInformationCommand [device=COO, channel=11, power=-7, panId=30305, epanId=37FDDB4D24F01102, status=SUCCESS]

2019-01-04 17:40:24.928 [DEBUG] [zigbee.transaction.ZigBeeTransaction] - Transaction timeout: SimpleDescriptorRequest [0/0 -> 41484/0, cluster=0004, TID=E5, nwkAddrOfInterest=41484, endpoint=1]

2019-01-04 17:40:24.930 [DEBUG] [iscovery.ZigBeeNodeServiceDiscoverer] - 000D6F000B9386E0: Node SVC Discovery: SimpleDescriptorResponse returned null

2019-01-04 17:40:24.933 [DEBUG] [iscovery.ZigBeeNodeServiceDiscoverer] - 000D6F000B9386E0: Node SVC Discovery: request ACTIVE_ENDPOINTS failed. Retry 1, wait 2166ms before retry.

2019-01-04 17:40:24.936 [DEBUG] [zigbee.transaction.ZigBeeTransaction] - Transaction timeout: SimpleDescriptorRequest [0/0 -> 41484/0, cluster=0004, TID=E6, nwkAddrOfInterest=41484, endpoint=2]

2019-01-04 17:40:24.938 [DEBUG] [iscovery.ZigBeeNodeServiceDiscoverer] - 000D6F000B9386E0: Node SVC Discovery: SimpleDescriptorResponse returned null

2019-01-04 17:40:24.940 [DEBUG] [iscovery.ZigBeeNodeServiceDiscoverer] - 000D6F000B9386E0: Node SVC Discovery: request ACTIVE_ENDPOINTS failed. Retry 1, wait 2166ms before retry.

2019-01-04 17:40:25.330 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:NEWNODE:BD29,000D6F000B9386E0,0000

2019-01-04 17:40:25.333 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDeviceJoinedNetworkEvent [networkAddress=48425, ieeeAddress=000D6F000B9386E0, parentAddress=0]

2019-01-04 17:40:25.335 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - 000D6F000B9386E0: nodeStatusUpdate - node status is UNSECURED_JOIN, network address is 48425.

2019-01-04 17:40:25.338 [DEBUG] [pp.discovery.ZigBeeNetworkDiscoverer] - 000D6F000B9386E0: Device status updated. NWK=48425

2019-01-04 17:40:25.342 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - 000D6F000B9386E0: Node 48425 added to the network

2019-01-04 17:40:25.342 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:RX:000D6F000B9386E0,BD29,0000,00,00,0013,0C:ƒ)½à†“

o

€,-53,FF

2019-01-04 17:40:25.346 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F000B9386E0: Node updated - ZigBeeNode [IEEE=000D6F000B9386E0, NWK=BD29, Type=UNKNOWN]

2019-01-04 17:40:25.352 [DEBUG] [p.discovery.ZigBeeDiscoveryExtension] - DISCOVERY Extension: Adding discoverer for 000D6F000B9386E0

2019-01-04 17:40:25.354 [DEBUG] [iscovery.ZigBeeNodeServiceDiscoverer] - 000D6F000B9386E0: Node SVC Discovery: start discovery

2019-01-04 17:40:25.356 [INFO ] [bee.discovery.ZigBeeDiscoveryService] - 000D6F000B9386E0: Starting ZigBee device discovery

2019-01-04 17:40:25.357 [DEBUG] [iscovery.ZigBeeNodeServiceDiscoverer] - 000D6F000B9386E0: Node SVC Discovery: scheduled [NWK_ADDRESS, ACTIVE_ENDPOINTS, NODE_DESCRIPTOR, POWER_DESCRIPTOR, NEIGHBORS]

2019-01-04 17:40:25.359 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - 000D6F000B9386E0: Creating ZigBee device zigbee:device with bridge zigbee:coordinator_telegesis:04000B3A

2019-01-04 17:40:25.363 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - 000D6F000B9386E0: Node discovery not complete

2019-01-04 17:40:25.345 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisReceiveMessageEvent [ieeeAddress=000D6F000B9386E0, networkAddress=48425, profileId=0, destinationEp=0, sourceEp=0, clusterId=19, messageData=83 29 BD E0 86 93 0B 00 6F 0D 00 80, rssi=-83, lqi=255]

2019-01-04 17:40:25.369 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Start.

2019-01-04 17:40:25.373 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=48425/0, destinationAddress=0/0, profile=0000, cluster=19, addressMode=null, radius=0, apsSecurity=false, apsCounter=0, payload=83 29 BD E0 86 93 0B 00 6F 0D 00 80]

==> /var/log/openhab2/events.log <==

2019-01-04 17:40:25.375 [me.event.ThingUpdatedEvent] - Thing 'zigbee:device:04000B3A:000d6f000b9386e0' has been updated.

==> /var/log/openhab2/openhab.log <==

2019-01-04 17:40:25.379 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: DeviceAnnounce [48425/0 -> 0/0, cluster=0013, TID=NULL, nwkAddrOfInterest=48425, ieeeAddr=000D6F000B9386E0, capability=128]

2019-01-04 17:40:25.383 [DEBUG] [pp.discovery.ZigBeeNetworkDiscoverer] - 000D6F000B9386E0: Device announce received. NWK=48425

2019-01-04 17:40:25.386 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - 000D6F000B9386E0: Node 48425 update

2019-01-04 17:40:25.392 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F000B9386E0: Node has not finished discovery

2019-01-04 17:40:25.403 [DEBUG] [p.discovery.ZigBeeDiscoveryExtension] - DISCOVERY Extension: Mesh related command received. Triggering mesh update.

2019-01-04 17:40:25.406 [DEBUG] [p.discovery.ZigBeeDiscoveryExtension] - DISCOVERY Extension: Start mesh update task with interval of 300 seconds

2019-01-04 17:40:25.413 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Done.

2019-01-04 17:40:25.419 [DEBUG] [p.discovery.ZigBeeDiscoveryExtension] - DISCOVERY Extension: Starting mesh update

2019-01-04 17:40:25.420 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F000B9386E0: Node updated - ZigBeeNode [IEEE=000D6F000B9386E0, NWK=BD29, Type=UNKNOWN]

2019-01-04 17:40:25.423 [DEBUG] [p.discovery.ZigBeeDiscoveryExtension] - DISCOVERY Extension: Starting mesh update for 000D6F000B9386E0

==> /var/log/openhab2/events.log <==

2019-01-04 17:40:25.424 [hingStatusInfoChangedEvent] - 'zigbee:device:04000B3A:000d6f000b9386e0' changed from OFFLINE to OFFLINE: Node has not completed discovery

==> /var/log/openhab2/openhab.log <==

2019-01-04 17:40:25.427 [DEBUG] [iscovery.ZigBeeNodeServiceDiscoverer] - 000D6F000B9386E0: Node SVC Discovery: Update mesh

2019-01-04 17:40:25.437 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F000B9386E0: Node has not finished discovery

2019-01-04 17:40:25.441 [DEBUG] [iscovery.ZigBeeNodeServiceDiscoverer] - 000D6F000B9386E0: Node SVC Discovery: already scheduled or running

2019-01-04 17:40:25.442 [INFO ] [bee.discovery.ZigBeeDiscoveryService] - 000D6F000B9386E0: Starting ZigBee device discovery

2019-01-04 17:40:25.446 [DEBUG] [iscovery.ZigBeeNodeServiceDiscoverer] - 000D6F000B9386E0: Node SVC Discovery: scheduled [NWK_ADDRESS, ACTIVE_ENDPOINTS, NODE_DESCRIPTOR, POWER_DESCRIPTOR, NEIGHBORS, ROUTES]

2019-01-04 17:40:25.448 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - 000D6F000B9386E0: Creating ZigBee device zigbee:device with bridge zigbee:coordinator_telegesis:04000B3A

2019-01-04 17:40:25.449 [DEBUG] [p.discovery.ZigBeeDiscoveryExtension] - DISCOVERY Extension: Starting mesh update for 000D6F00108DEAEA

2019-01-04 17:40:25.451 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Start.

2019-01-04 17:40:25.452 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - 000D6F000B9386E0: Node discovery not complete

2019-01-04 17:40:25.455 [DEBUG] [iscovery.ZigBeeNodeServiceDiscoverer] - 000D6F00108DEAEA: Node SVC Discovery: Update mesh

2019-01-04 17:40:25.458 [DEBUG] [iscovery.ZigBeeNodeServiceDiscoverer] - 000D6F00108DEAEA: Node SVC Discovery: scheduled [ROUTES, NEIGHBORS]

2019-01-04 17:40:25.467 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Done.

2019-01-04 17:40:25.542 [DEBUG] [iscovery.ZigBeeNodeServiceDiscoverer] - 000D6F00108DEAEA: Node SVC Discovery: running

2019-01-04 17:40:25.543 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ManagementRoutingRequest [0/0 -> 0/0, cluster=0032, TID=E9, startIndex=0]

2019-01-04 17:40:25.545 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=50, addressMode=DEVICE, radius=31, apsSecurity=false, apsCounter=233, payload=00 00]

2019-01-04 17:40:25.547 [DEBUG] [ngle.telegesis.ZigBeeDongleTelegesis] - Telegesis send: TelegesisSendUnicastCommand [address=0, sourceEp=0, destEp=0, profileId=0, clusterId=50, messageData=00 00, messageId=null]

2019-01-04 17:40:25.548 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1

2019-01-04 17:40:25.550 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisSendUnicastCommand [address=0, sourceEp=0, destEp=0, profileId=0, clusterId=50, messageData=00 00, messageId=null]

2019-01-04 17:40:25.552 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+SENDUCASTB:02,0000,00,00,0000,0032

2019-01-04 17:40:25.754 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:SEQ:A9

2019-01-04 17:40:25.756 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK

2019-01-04 17:40:25.757 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisSendUnicastCommand [address=0, sourceEp=0, destEp=0, profileId=0, clusterId=50, messageData=00 00, messageId=169, status=SUCCESS]

2019-01-04 17:40:25.763 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:RX:0000,0000,00,00,8032,50:@,00,FF

2019-01-04 17:40:25.765 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisReceiveMessageEvent [ieeeAddress=null, networkAddress=0, profileId=0, destinationEp=0, sourceEp=0, clusterId=32818, messageData=00 00 40 00 0F 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00, rssi=0, lqi=255]

2019-01-04 17:40:25.768 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=32818, addressMode=null, radius=0, apsSecurity=false, apsCounter=0, payload=00 00 40 00 0F 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00]

2019-01-04 17:40:25.770 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ManagementRoutingResponse [0/0 -> 0/0, cluster=8032, TID=NULL, status=SUCCESS, routingTableEntries=64, startIndex=0, routingTableList=[RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0]]]

2019-01-04 17:40:25.774 [DEBUG] [zigbee.transaction.ZigBeeTransaction] - Transaction complete: ManagementRoutingRequest [0/0 -> 0/0, cluster=0032, TID=E9, startIndex=0]

2019-01-04 17:40:25.777 [DEBUG] [iscovery.ZigBeeNodeServiceDiscoverer] - 000D6F00108DEAEA: Node SVC Discovery: ManagementRoutingRequest returned CommandResult [SUCCESS, ManagementRoutingResponse [0/0 -> 0/0, cluster=8032, TID=NULL, status=SUCCESS, routingTableEntries=64, startIndex=0, routingTableList=[RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0]]]]

2019-01-04 17:40:25.778 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:ACK:A9

2019-01-04 17:40:25.779 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisAckMessageEvent [messageId=169]

2019-01-04 17:40:25.779 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ManagementRoutingRequest [0/0 -> 0/0, cluster=0032, TID=EA, startIndex=15]

2019-01-04 17:40:25.780 [DEBUG] [ngle.telegesis.ZigBeeDongleTelegesis] - Unhandled Telegesis Frame: TelegesisAckMessageEvent [messageId=169]

2019-01-04 17:40:25.781 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=50, addressMode=DEVICE, radius=31, apsSecurity=false, apsCounter=234, payload=00 0F]

2019-01-04 17:40:25.782 [DEBUG] [ngle.telegesis.ZigBeeDongleTelegesis] - Telegesis send: TelegesisSendUnicastCommand [address=0, sourceEp=0, destEp=0, profileId=0, clusterId=50, messageData=00 0F, messageId=null]

2019-01-04 17:40:25.783 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1

2019-01-04 17:40:25.785 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisSendUnicastCommand [address=0, sourceEp=0, destEp=0, profileId=0, clusterId=50, messageData=00 0F, messageId=null]

2019-01-04 17:40:25.787 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+SENDUCASTB:02,0000,00,00,0000,0032



2019-01-04 17:40:25.990 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:SEQ:AB

2019-01-04 17:40:25.991 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK

2019-01-04 17:40:25.993 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisSendUnicastCommand [address=0, sourceEp=0, destEp=0, profileId=0, clusterId=50, messageData=00 0F, messageId=171, status=SUCCESS]

2019-01-04 17:40:26.001 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:RX:0000,0000,00,00,8032,50:@,00,FF

2019-01-04 17:40:26.005 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisReceiveMessageEvent [ieeeAddress=null, networkAddress=0, profileId=0, destinationEp=0, sourceEp=0, clusterId=32818, messageData=00 00 40 0F 0F 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00, rssi=0, lqi=255]

2019-01-04 17:40:26.008 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=32818, addressMode=null, radius=0, apsSecurity=false, apsCounter=0, payload=00 00 40 0F 0F 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00]

2019-01-04 17:40:26.010 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ManagementRoutingResponse [0/0 -> 0/0, cluster=8032, TID=NULL, status=SUCCESS, routingTableEntries=64, startIndex=15, routingTableList=[RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0]]]

2019-01-04 17:40:26.016 [DEBUG] [zigbee.transaction.ZigBeeTransaction] - Transaction complete: ManagementRoutingRequest [0/0 -> 0/0, cluster=0032, TID=EA, startIndex=15]

2019-01-04 17:40:26.019 [DEBUG] [iscovery.ZigBeeNodeServiceDiscoverer] - 000D6F00108DEAEA: Node SVC Discovery: ManagementRoutingRequest returned CommandResult [SUCCESS, ManagementRoutingResponse [0/0 -> 0/0, cluster=8032, TID=NULL, status=SUCCESS, routingTableEntries=64, startIndex=15, routingTableList=[RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0]]]]

2019-01-04 17:40:26.020 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:ACK:AB

2019-01-04 17:40:26.021 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ManagementRoutingRequest [0/0 -> 0/0, cluster=0032, TID=EB, startIndex=30]

2019-01-04 17:40:26.021 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisAckMessageEvent [messageId=171]

2019-01-04 17:40:26.023 [DEBUG] [ngle.telegesis.ZigBeeDongleTelegesis] - Unhandled Telegesis Frame: TelegesisAckMessageEvent [messageId=171]

2019-01-04 17:40:26.023 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=50, addressMode=DEVICE, radius=31, apsSecurity=false, apsCounter=235, payload=00 1E]

2019-01-04 17:40:26.025 [DEBUG] [ngle.telegesis.ZigBeeDongleTelegesis] - Telegesis send: TelegesisSendUnicastCommand [address=0, sourceEp=0, destEp=0, profileId=0, clusterId=50, messageData=00 1E, messageId=null]

2019-01-04 17:40:26.026 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1

2019-01-04 17:40:26.028 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisSendUnicastCommand [address=0, sourceEp=0, destEp=0, profileId=0, clusterId=50, messageData=00 1E, messageId=null]

2019-01-04 17:40:26.031 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+SENDUCASTB:02,0000,00,00,0000,0032



2019-01-04 17:40:26.234 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:SEQ:AD

2019-01-04 17:40:26.237 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK

2019-01-04 17:40:26.240 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisSendUnicastCommand [address=0, sourceEp=0, destEp=0, profileId=0, clusterId=50, messageData=00 1E, messageId=173, status=SUCCESS]

2019-01-04 17:40:26.253 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:RX:0000,0000,00,00,8032,50:@,00,FF

2019-01-04 17:40:26.258 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisReceiveMessageEvent [ieeeAddress=null, networkAddress=0, profileId=0, destinationEp=0, sourceEp=0, clusterId=32818, messageData=00 00 40 1E 0F 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00, rssi=0, lqi=255]

2019-01-04 17:40:26.262 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=32818, addressMode=null, radius=0, apsSecurity=false, apsCounter=0, payload=00 00 40 1E 0F 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00]

2019-01-04 17:40:26.266 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ManagementRoutingResponse [0/0 -> 0/0, cluster=8032, TID=NULL, status=SUCCESS, routingTableEntries=64, startIndex=30, routingTableList=[RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0]]]

2019-01-04 17:40:26.272 [DEBUG] [zigbee.transaction.ZigBeeTransaction] - Transaction complete: ManagementRoutingRequest [0/0 -> 0/0, cluster=0032, TID=EB, startIndex=30]

2019-01-04 17:40:26.276 [DEBUG] [iscovery.ZigBeeNodeServiceDiscoverer] - 000D6F00108DEAEA: Node SVC Discovery: ManagementRoutingRequest returned CommandResult [SUCCESS, ManagementRoutingResponse [0/0 -> 0/0, cluster=8032, TID=NULL, status=SUCCESS, routingTableEntries=64, startIndex=30, routingTableList=[RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0]]]]

2019-01-04 17:40:26.276 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:ACK:AD

2019-01-04 17:40:26.279 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ManagementRoutingRequest [0/0 -> 0/0, cluster=0032, TID=EC, startIndex=45]

2019-01-04 17:40:26.280 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisAckMessageEvent [messageId=173]

2019-01-04 17:40:26.282 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=50, addressMode=DEVICE, radius=31, apsSecurity=false, apsCounter=236, payload=00 2D]

2019-01-04 17:40:26.283 [DEBUG] [ngle.telegesis.ZigBeeDongleTelegesis] - Unhandled Telegesis Frame: TelegesisAckMessageEvent [messageId=173]

2019-01-04 17:40:26.284 [DEBUG] [ngle.telegesis.ZigBeeDongleTelegesis] - Telegesis send: TelegesisSendUnicastCommand [address=0, sourceEp=0, destEp=0, profileId=0, clusterId=50, messageData=00 2D, messageId=null]

2019-01-04 17:40:26.286 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1

2019-01-04 17:40:26.288 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisSendUnicastCommand [address=0, sourceEp=0, destEp=0, profileId=0, clusterId=50, messageData=00 2D, messageId=null]

2019-01-04 17:40:26.293 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+SENDUCASTB:02,0000,00,00,0000,0032

-

2019-01-04 17:40:26.324 [DEBUG] [iscovery.ZigBeeNodeServiceDiscoverer] - 000D6F000B9386E0: Node SVC Discovery: running

2019-01-04 17:40:26.327 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: NetworkAddressRequest [0/0 -> 65535/0, cluster=0000, TID=ED, ieeeAddr=000D6F000B9386E0, requestType=0, startIndex=0]

2019-01-04 17:40:26.329 [DEBUG] [iscovery.ZigBeeNodeServiceDiscoverer] - 000D6F000B9386E0: Node SVC Discovery: running

2019-01-04 17:40:26.330 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=65535/0, profile=0000, cluster=0, addressMode=DEVICE, radius=31, apsSecurity=false, apsCounter=237, payload=00 E0 86 93 0B 00 6F 0D 00 00 00]

2019-01-04 17:40:26.332 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: NetworkAddressRequest [0/0 -> 65535/0, cluster=0000, TID=EE, ieeeAddr=000D6F000B9386E0, requestType=0, startIndex=0]

2019-01-04 17:40:26.334 [DEBUG] [ngle.telegesis.ZigBeeDongleTelegesis] - Telegesis send: TelegesisSendMulticastCommand [radius=31, address=65535, sourceEp=0, destEp=0, profileId=0, clusterId=0, messageData=00 E0 86 93 0B 00 6F 0D 00 00 00]

2019-01-04 17:40:26.335 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=65535/0, profile=0000, cluster=0, addressMode=DEVICE, radius=31, apsSecurity=false, apsCounter=238, payload=00 E0 86 93 0B 00 6F 0D 00 00 00]

2019-01-04 17:40:26.336 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1

2019-01-04 17:40:26.339 [DEBUG] [ngle.telegesis.ZigBeeDongleTelegesis] - Telegesis send: TelegesisSendMulticastCommand [radius=31, address=65535, sourceEp=0, destEp=0, profileId=0, clusterId=0, messageData=00 E0 86 93 0B 00 6F 0D 00 00 00]

2019-01-04 17:40:26.341 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 2

2019-01-04 17:40:26.497 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:SEQ:AF

2019-01-04 17:40:26.500 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK

2019-01-04 17:40:26.503 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisSendUnicastCommand [address=0, sourceEp=0, destEp=0, profileId=0, clusterId=50, messageData=00 2D, messageId=175, status=SUCCESS]

2019-01-04 17:40:26.508 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisSendMulticastCommand [radius=31, address=65535, sourceEp=0, destEp=0, profileId=0, clusterId=0, messageData=00 E0 86 93 0B 00 6F 0D 00 00 00]

2019-01-04 17:40:26.514 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+SENDMCASTB:0B,1F,FFFF,00,00,0000,0000

à†“

o

2019-01-04 17:40:26.525 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:RX:0000,0000,00,00,8032,50:@-,00,FF

2019-01-04 17:40:26.532 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisReceiveMessageEvent [ieeeAddress=null, networkAddress=0, profileId=0, destinationEp=0, sourceEp=0, clusterId=32818, messageData=00 00 40 2D 0F 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00, rssi=0, lqi=255]

2019-01-04 17:40:26.537 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=32818, addressMode=null, radius=0, apsSecurity=false, apsCounter=0, payload=00 00 40 2D 0F 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00]

2019-01-04 17:40:26.542 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ManagementRoutingResponse [0/0 -> 0/0, cluster=8032, TID=NULL, status=SUCCESS, routingTableEntries=64, startIndex=45, routingTableList=[RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0]]]

2019-01-04 17:40:26.551 [DEBUG] [zigbee.transaction.ZigBeeTransaction] - Transaction complete: ManagementRoutingRequest [0/0 -> 0/0, cluster=0032, TID=EC, startIndex=45]

2019-01-04 17:40:26.551 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:ACK:AF

2019-01-04 17:40:26.554 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisAckMessageEvent [messageId=175]

2019-01-04 17:40:26.554 [DEBUG] [iscovery.ZigBeeNodeServiceDiscoverer] - 000D6F00108DEAEA: Node SVC Discovery: ManagementRoutingRequest returned CommandResult [SUCCESS, ManagementRoutingResponse [0/0 -> 0/0, cluster=8032, TID=NULL, status=SUCCESS, routingTableEntries=64, startIndex=45, routingTableList=[RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0]]]]

2019-01-04 17:40:26.557 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ManagementRoutingRequest [0/0 -> 0/0, cluster=0032, TID=EF, startIndex=60]

2019-01-04 17:40:26.560 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=50, addressMode=DEVICE, radius=31, apsSecurity=false, apsCounter=239, payload=00 3C]

2019-01-04 17:40:26.564 [DEBUG] [ngle.telegesis.ZigBeeDongleTelegesis] - Telegesis send: TelegesisSendUnicastCommand [address=0, sourceEp=0, destEp=0, profileId=0, clusterId=50, messageData=00 3C, messageId=null]

2019-01-04 17:40:26.556 [DEBUG] [ngle.telegesis.ZigBeeDongleTelegesis] - Unhandled Telegesis Frame: TelegesisAckMessageEvent [messageId=175]

2019-01-04 17:40:26.566 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 2

2019-01-04 17:40:26.673 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK

2019-01-04 17:40:26.675 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisSendMulticastCommand [radius=31, address=65535, sourceEp=0, destEp=0, profileId=0, clusterId=0, messageData=00 E0 86 93 0B 00 6F 0D 00 00 00, status=SUCCESS]

2019-01-04 17:40:26.677 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisSendMulticastCommand [radius=31, address=65535, sourceEp=0, destEp=0, profileId=0, clusterId=0, messageData=00 E0 86 93 0B 00 6F 0D 00 00 00]

2019-01-04 17:40:26.680 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+SENDMCASTB:0B,1F,FFFF,00,00,0000,0000

à†“

o

2019-01-04 17:40:26.683 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:RX:0000,0000,00,00,8000,0C:à†“

o

)½,00,FF

2019-01-04 17:40:26.685 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisReceiveMessageEvent [ieeeAddress=null, networkAddress=0, profileId=0, destinationEp=0, sourceEp=0, clusterId=32768, messageData=00 00 E0 86 93 0B 00 6F 0D 00 29 BD, rssi=0, lqi=255]

2019-01-04 17:40:26.687 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=32768, addressMode=null, radius=0, apsSecurity=false, apsCounter=0, payload=00 00 E0 86 93 0B 00 6F 0D 00 29 BD]

2019-01-04 17:40:26.689 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: NetworkAddressResponse [0/0 -> 0/0, cluster=8000, TID=NULL, status=SUCCESS, ieeeAddrRemoteDev=000D6F000B9386E0, nwkAddrRemoteDev=48425, startIndex=null, nwkAddrAssocDevList=[]]

2019-01-04 17:40:26.693 [DEBUG] [zigbee.transaction.ZigBeeTransaction] - Transaction complete: NetworkAddressRequest [0/0 -> 65535/0, cluster=0000, TID=ED, ieeeAddr=000D6F000B9386E0, requestType=0, startIndex=0]

2019-01-04 17:40:26.694 [DEBUG] [iscovery.ZigBeeNodeServiceDiscoverer] - 48425: Node SVC Discovery: NetworkAddressRequest returned NetworkAddressResponse [0/0 -> 0/0, cluster=8000, TID=NULL, status=SUCCESS, ieeeAddrRemoteDev=000D6F000B9386E0, nwkAddrRemoteDev=48425, startIndex=null, nwkAddrAssocDevList=[]]

2019-01-04 17:40:26.695 [DEBUG] [zigbee.transaction.ZigBeeTransaction] - Transaction complete: NetworkAddressRequest [0/0 -> 65535/0, cluster=0000, TID=EE, ieeeAddr=000D6F000B9386E0, requestType=0, startIndex=0]

2019-01-04 17:40:26.696 [DEBUG] [iscovery.ZigBeeNodeServiceDiscoverer] - 000D6F000B9386E0: Node SVC Discovery: request NWK_ADDRESS successful. Advanced to ACTIVE_ENDPOINTS.

2019-01-04 17:40:26.697 [DEBUG] [iscovery.ZigBeeNodeServiceDiscoverer] - 48425: Node SVC Discovery: NetworkAddressRequest returned NetworkAddressResponse [0/0 -> 0/0, cluster=8000, TID=NULL, status=SUCCESS, ieeeAddrRemoteDev=000D6F000B9386E0, nwkAddrRemoteDev=48425, startIndex=null, nwkAddrAssocDevList=[]]

2019-01-04 17:40:26.697 [DEBUG] [iscovery.ZigBeeNodeServiceDiscoverer] - 000D6F000B9386E0: Node SVC Discovery: running

2019-01-04 17:40:26.699 [DEBUG] [iscovery.ZigBeeNodeServiceDiscoverer] - 000D6F000B9386E0: Node SVC Discovery: request NWK_ADDRESS successful. Advanced to ACTIVE_ENDPOINTS.

2019-01-04 17:40:26.699 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ActiveEndpointsRequest [0/0 -> 48425/0, cluster=0005, TID=F0, nwkAddrOfInterest=48425]

2019-01-04 17:40:26.700 [DEBUG] [iscovery.ZigBeeNodeServiceDiscoverer] - 000D6F000B9386E0: Node SVC Discovery: running

2019-01-04 17:40:26.700 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=48425/0, profile=0000, cluster=5, addressMode=DEVICE, radius=31, apsSecurity=false, apsCounter=240, payload=00 29 BD]

2019-01-04 17:40:26.702 [DEBUG] [ngle.telegesis.ZigBeeDongleTelegesis] - Telegesis send: TelegesisSendUnicastCommand [address=48425, sourceEp=0, destEp=0, profileId=0, clusterId=5, messageData=00 29 BD, messageId=null]

2019-01-04 17:40:26.702 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ActiveEndpointsRequest [0/0 -> 48425/0, cluster=0005, TID=F1, nwkAddrOfInterest=48425]

2019-01-04 17:40:26.704 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=48425/0, profile=0000, cluster=5, addressMode=DEVICE, radius=31, apsSecurity=false, apsCounter=241, payload=00 29 BD]

2019-01-04 17:40:26.704 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 2

2019-01-04 17:40:26.706 [DEBUG] [ngle.telegesis.ZigBeeDongleTelegesis] - Telegesis send: TelegesisSendUnicastCommand [address=48425, sourceEp=0, destEp=0, profileId=0, clusterId=5, messageData=00 29 BD, messageId=null]

2019-01-04 17:40:26.707 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 3

2019-01-04 17:40:26.839 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK

2019-01-04 17:40:26.841 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisSendMulticastCommand [radius=31, address=65535, sourceEp=0, destEp=0, profileId=0, clusterId=0, messageData=00 E0 86 93 0B 00 6F 0D 00 00 00, status=SUCCESS]

2019-01-04 17:40:26.842 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisSendUnicastCommand [address=0, sourceEp=0, destEp=0, profileId=0, clusterId=50, messageData=00 3C, messageId=null]

2019-01-04 17:40:26.845 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+SENDUCASTB:02,0000,00,00,0000,0032

<

2019-01-04 17:40:26.848 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:RX:0000,0000,00,00,8000,0C:à†“

o

)½,00,FF

2019-01-04 17:40:26.850 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisReceiveMessageEvent [ieeeAddress=null, networkAddress=0, profileId=0, destinationEp=0, sourceEp=0, clusterId=32768, messageData=00 00 E0 86 93 0B 00 6F 0D 00 29 BD, rssi=0, lqi=255]

2019-01-04 17:40:26.852 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=32768, addressMode=null, radius=0, apsSecurity=false, apsCounter=0, payload=00 00 E0 86 93 0B 00 6F 0D 00 29 BD]

2019-01-04 17:40:26.854 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: NetworkAddressResponse [0/0 -> 0/0, cluster=8000, TID=NULL, status=SUCCESS, ieeeAddrRemoteDev=000D6F000B9386E0, nwkAddrRemoteDev=48425, startIndex=null, nwkAddrAssocDevList=[]]

2019-01-04 17:40:27.017 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:SEQ:B5

2019-01-04 17:40:27.019 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK

2019-01-04 17:40:27.021 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisSendUnicastCommand [address=0, sourceEp=0, destEp=0, profileId=0, clusterId=50, messageData=00 3C, messageId=181, status=SUCCESS]

2019-01-04 17:40:27.022 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisSendUnicastCommand [address=48425, sourceEp=0, destEp=0, profileId=0, clusterId=5, messageData=00 29 BD, messageId=null]

2019-01-04 17:40:27.025 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+SENDUCASTB:03,BD29,00,00,0000,0005

)½

2019-01-04 17:40:27.028 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:RX:0000,0000,00,00,8032,19:@<,00,FF

2019-01-04 17:40:27.030 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisReceiveMessageEvent [ieeeAddress=null, networkAddress=0, profileId=0, destinationEp=0, sourceEp=0, clusterId=32818, messageData=00 00 40 3C 04 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00, rssi=0, lqi=255]

2019-01-04 17:40:27.032 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=32818, addressMode=null, radius=0, apsSecurity=false, apsCounter=0, payload=00 00 40 3C 04 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00 00 00 03 00 00]

2019-01-04 17:40:27.039 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ManagementRoutingResponse [0/0 -> 0/0, cluster=8032, TID=NULL, status=SUCCESS, routingTableEntries=64, startIndex=60, routingTableList=[RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0]]]

2019-01-04 17:40:27.040 [DEBUG] [zigbee.transaction.ZigBeeTransaction] - Transaction complete: ManagementRoutingRequest [0/0 -> 0/0, cluster=0032, TID=EF, startIndex=60]

2019-01-04 17:40:27.043 [DEBUG] [iscovery.ZigBeeNodeServiceDiscoverer] - 000D6F00108DEAEA: Node SVC Discovery: ManagementRoutingRequest returned CommandResult [SUCCESS, ManagementRoutingResponse [0/0 -> 0/0, cluster=8032, TID=NULL, status=SUCCESS, routingTableEntries=64, startIndex=60, routingTableList=[RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0]]]]

2019-01-04 17:40:27.044 [DEBUG] [iscovery.ZigBeeNodeServiceDiscoverer] - 000D6F00108DEAEA: Node SVC Discovery: ManagementLqiRequest complete [1 routes]

2019-01-04 17:40:27.046 [DEBUG] [com.zsmartsystems.zigbee.ZigBeeNode ] - 000D6F00108DEAEA: Routing table NEW: [RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0]]

2019-01-04 17:40:27.047 [DEBUG] [com.zsmartsystems.zigbee.ZigBeeNode ] - 000D6F00108DEAEA: Routing table OLD: [RoutingTable [destinationAddress=14237, status=DISCOVERY_UNDERWAY, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0], RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0]]

2019-01-04 17:40:27.049 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:ACK:B5

2019-01-04 17:40:27.049 [DEBUG] [com.zsmartsystems.zigbee.ZigBeeNode ] - 000D6F00108DEAEA: Routing table updated: [RoutingTable [destinationAddress=0, status=INACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=0]]

2019-01-04 17:40:27.050 [DEBUG] [iscovery.ZigBeeNodeServiceDiscoverer] - 000D6F00108DEAEA: Node SVC Discovery: request ROUTES successful. Advanced to NEIGHBORS.

2019-01-04 17:40:27.050 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisAckMessageEvent [messageId=181]

2019-01-04 17:40:27.051 [DEBUG] [ngle.telegesis.ZigBeeDongleTelegesis] - Unhandled Telegesis Frame: TelegesisAckMessageEvent [messageId=181]

2019-01-04 17:40:27.052 [DEBUG] [iscovery.ZigBeeNodeServiceDiscoverer] - 000D6F00108DEAEA: Node SVC Discovery: running

2019-01-04 17:40:27.053 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ManagementLqiRequest [0/0 -> 0/0, cluster=0031, TID=F2, startIndex=0]

2019-01-04 17:40:27.055 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=49, addressMode=DEVICE, radius=31, apsSecurity=false, apsCounter=242, payload=00 00]

2019-01-04 17:40:27.057 [DEBUG] [ngle.telegesis.ZigBeeDongleTelegesis] - Telegesis send: TelegesisSendUnicastCommand [address=0, sourceEp=0, destEp=0, profileId=0, clusterId=49, messageData=00 00, messageId=null]

2019-01-04 17:40:27.058 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 2

2019-01-04 17:40:27.108 [DEBUG] [iscovery.ZigBeeNodeServiceDiscoverer] - 000D6F000B9386E0: Node SVC Discovery: running

2019-01-04 17:40:27.110 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ActiveEndpointsRequest [0/0 -> 41484/0, cluster=0005, TID=F3, nwkAddrOfInterest=41484]

2019-01-04 17:40:27.112 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=41484/0, profile=0000, cluster=5, addressMode=DEVICE, radius=31, apsSecurity=false, apsCounter=243, payload=00 0C A2]

2019-01-04 17:40:27.113 [DEBUG] [ngle.telegesis.ZigBeeDongleTelegesis] - Telegesis send: TelegesisSendUnicastCommand [address=41484, sourceEp=0, destEp=0, profileId=0, clusterId=5, messageData=00 0C A2, messageId=null]

2019-01-04 17:40:27.115 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 3

2019-01-04 17:40:27.159 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:SEQ:B7

2019-01-04 17:40:27.161 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK

2019-01-04 17:40:27.163 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisSendUnicastCommand [address=48425, sourceEp=0, destEp=0, profileId=0, clusterId=5, messageData=00 29 BD, messageId=183, status=SUCCESS]

2019-01-04 17:40:27.165 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisSendUnicastCommand [address=48425, sourceEp=0, destEp=0, profileId=0, clusterId=5, messageData=00 29 BD, messageId=null]

2019-01-04 17:40:27.169 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+SENDUCASTB:03,BD29,00,00,0000,0005

)½

2019-01-04 17:40:27.397 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:SEQ:B8

2019-01-04 17:40:27.400 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK

2019-01-04 17:40:27.402 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisSendUnicastCommand [address=48425, sourceEp=0, destEp=0, profileId=0, clusterId=5, messageData=00 29 BD, messageId=184, status=SUCCESS]

2019-01-04 17:40:27.404 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisSendUnicastCommand [address=0, sourceEp=0, destEp=0, profileId=0, clusterId=49, messageData=00 00, messageId=null]

2019-01-04 17:40:27.409 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+SENDUCASTB:02,0000,00,00,0000,0031

2019-01-04 17:40:27.414 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:RX:BD29,0000,00,00,8005,07:)½
,-53,FF

2019-01-04 17:40:27.416 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisReceiveMessageEvent [ieeeAddress=null, networkAddress=48425, profileId=0, destinationEp=0, sourceEp=0, clusterId=32773, messageData=00 00 29 BD 02 01 02, rssi=-83, lqi=255]

2019-01-04 17:40:27.418 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=48425/0, destinationAddress=0/0, profile=0000, cluster=32773, addressMode=null, radius=0, apsSecurity=false, apsCounter=0, payload=00 00 29 BD 02 01 02]

2019-01-04 17:40:27.421 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ActiveEndpointsResponse [48425/0 -> 0/0, cluster=8005, TID=NULL, status=SUCCESS, nwkAddrOfInterest=48425, activeEpList=[1, 2]]

2019-01-04 17:40:27.423 [DEBUG] [zigbee.transaction.ZigBeeTransaction] - Transaction complete: ActiveEndpointsRequest [0/0 -> 48425/0, cluster=0005, TID=F0, nwkAddrOfInterest=48425]

2019-01-04 17:40:27.423 [DEBUG] [zigbee.transaction.ZigBeeTransaction] - Transaction complete: ActiveEndpointsRequest [0/0 -> 48425/0, cluster=0005, TID=F1, nwkAddrOfInterest=48425]

2019-01-04 17:40:27.427 [DEBUG] [iscovery.ZigBeeNodeServiceDiscoverer] - 000D6F000B9386E0: Node SVC Discovery: ActiveEndpointsResponse returned CommandResult [SUCCESS, ActiveEndpointsResponse [48425/0 -> 0/0, cluster=8005, TID=NULL, status=SUCCESS, nwkAddrOfInterest=48425, activeEpList=[1, 2]]]

2019-01-04 17:40:27.428 [DEBUG] [iscovery.ZigBeeNodeServiceDiscoverer] - 000D6F000B9386E0: Node SVC Discovery: ActiveEndpointsResponse returned CommandResult [SUCCESS, ActiveEndpointsResponse [48425/0 -> 0/0, cluster=8005, TID=NULL, status=SUCCESS, nwkAddrOfInterest=48425, activeEpList=[1, 2]]]

2019-01-04 17:40:27.437 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: SimpleDescriptorRequest [0/0 -> 48425/0, cluster=0004, TID=F5, nwkAddrOfInterest=48425, endpoint=1]

2019-01-04 17:40:27.438 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:ACK:B7

2019-01-04 17:40:27.437 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: SimpleDescriptorRequest [0/0 -> 48425/0, cluster=0004, TID=F4, nwkAddrOfInterest=48425, endpoint=1]

2019-01-04 17:40:27.440 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisAckMessageEvent [messageId=183]

2019-01-04 17:40:27.440 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=48425/0, profile=0000, cluster=4, addressMode=DEVICE, radius=31, apsSecurity=false, apsCounter=244, payload=00 29 BD 01]

2019-01-04 17:40:27.442 [DEBUG] [ngle.telegesis.ZigBeeDongleTelegesis] - Unhandled Telegesis Frame: TelegesisAckMessageEvent [messageId=183]

2019-01-04 17:40:27.443 [DEBUG] [ngle.telegesis.ZigBeeDongleTelegesis] - Telegesis send: TelegesisSendUnicastCommand [address=48425, sourceEp=0, destEp=0, profileId=0, clusterId=4, messageData=00 29 BD 01, messageId=null]

2019-01-04 17:40:27.445 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 2

2019-01-04 17:40:27.448 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:RX:BD29,0000,00,00,8005,07:)½
,-53,FF

2019-01-04 17:40:27.450 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisReceiveMessageEvent [ieeeAddress=null, networkAddress=48425, profileId=0, destinationEp=0, sourceEp=0, clusterId=32773, messageData=00 00 29 BD 02 01 02, rssi=-83, lqi=255]

2019-01-04 17:40:27.450 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=48425/0, profile=0000, cluster=4, addressMode=DEVICE, radius=31, apsSecurity=false, apsCounter=245, payload=00 29 BD 01]

2019-01-04 17:40:27.453 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=48425/0, destinationAddress=0/0, profile=0000, cluster=32773, addressMode=null, radius=0, apsSecurity=false, apsCounter=0, payload=00 00 29 BD 02 01 02]

2019-01-04 17:40:27.453 [DEBUG] [ngle.telegesis.ZigBeeDongleTelegesis] - Telegesis send: TelegesisSendUnicastCommand [address=48425, sourceEp=0, destEp=0, profileId=0, clusterId=4, messageData=00 29 BD 01, messageId=null]

2019-01-04 17:40:27.455 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ActiveEndpointsResponse [48425/0 -> 0/0, cluster=8005, TID=NULL, status=SUCCESS, nwkAddrOfInterest=48425, activeEpList=[1, 2]]

2019-01-04 17:40:27.455 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 3

2019-01-04 17:40:27.468 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:ACK:B8

2019-01-04 17:40:27.470 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisAckMessageEvent [messageId=184]

2019-01-04 17:40:27.473 [DEBUG] [ngle.telegesis.ZigBeeDongleTelegesis] - Unhandled Telegesis Frame: TelegesisAckMessageEvent [messageId=184]

2019-01-04 17:40:27.479 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:RX:BD29,0000,00,00,8000,0C:à†“

o

)½,-53,FF

2019-01-04 17:40:27.482 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisReceiveMessageEvent [ieeeAddress=null, networkAddress=48425, profileId=0, destinationEp=0, sourceEp=0, clusterId=32768, messageData=00 00 E0 86 93 0B 00 6F 0D 00 29 BD, rssi=-83, lqi=255]

2019-01-04 17:40:27.485 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=48425/0, destinationAddress=0/0, profile=0000, cluster=32768, addressMode=null, radius=0, apsSecurity=false, apsCounter=0, payload=00 00 E0 86 93 0B 00 6F 0D 00 29 BD]

2019-01-04 17:40:27.488 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: NetworkAddressResponse [48425/0 -> 0/0, cluster=8000, TID=NULL, status=SUCCESS, ieeeAddrRemoteDev=000D6F000B9386E0, nwkAddrRemoteDev=48425, startIndex=null, nwkAddrAssocDevList=[]]

2019-01-04 17:40:27.584 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:SEQ:B9

2019-01-04 17:40:27.586 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK

2019-01-04 17:40:27.589 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisSendUnicastCommand [address=0, sourceEp=0, destEp=0, profileId=0, clusterId=49, messageData=00 00, messageId=185, status=SUCCESS]

2019-01-04 17:40:27.591 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisSendUnicastCommand [address=41484, sourceEp=0, destEp=0, profileId=0, clusterId=5, messageData=00 0C A2, messageId=null]

2019-01-04 17:40:27.595 [DEBUG] [gesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+SENDUCASTB:03,A20C,00,00,0000,0005

¢

2019-01-04 17:40:27.601 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:RX:0000,0000,00,00,8031,1B:

ð$MÛý7à†“

o

)½
ÿ,00,FF

2019-01-04 17:40:27.604 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisReceiveMessageEvent [ieeeAddress=null, networkAddress=0, profileId=0, destinationEp=0, sourceEp=0, clusterId=32817, messageData=00 00 01 00 01 02 11 F0 24 4D DB FD 37 E0 86 93 0B 00 6F 0D 00 29 BD 12 00 01 FF, rssi=0, lqi=255]

2019-01-04 17:40:27.607 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=32817, addressMode=null, radius=0, apsSecurity=false, apsCounter=0, payload=00 00 01 00 01 02 11 F0 24 4D DB FD 37 E0 86 93 0B 00 6F 0D 00 29 BD 12 00 01 FF]

2019-01-04 17:40:27.610 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ManagementLqiResponse [0/0 -> 0/0, cluster=8031, TID=NULL, status=SUCCESS, neighborTableEntries=1, startIndex=0, neighborTableList=[NeighborTable [extendedPanId=37FDDB4D24F01102, extendedAddress=000D6F000B9386E0, networkAddress=48425, deviceType=END_DEVICE, rxOnWhenIdle=RX_OFF, relationship=CHILD, permitJoining=DISABLED, depth=1, lqi=255]]]

2019-01-04 17:40:27.615 [DEBUG] [zigbee.transaction.ZigBeeTransaction] - Transaction complete: ManagementLqiRequest [0/0 -> 0/0, cluster=0031, TID=F2, startIndex=0]

2019-01-04 17:40:27.620 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis Data:ACK:B9

2019-01-04 17:40:27.621 [DEBUG] [iscovery.ZigBeeNodeServiceDiscoverer] - 000D6F00108DEAEA: Node SVC Discovery: ManagementLqiRequest response CommandResult [SUCCESS, ManagementLqiResponse [0/0 -> 0/0, cluster=8031, TID=NULL, status=SUCCESS, neighborTableEntries=1, startIndex=0, neighborTableList=[NeighborTable [extendedPanId=37FDDB4D24F01102, extendedAddress=000D6F000B9386E0, networkAddress=48425, deviceType=END_DEVICE, rxOnWhenIdle=RX_OFF, relationship=CHILD, permitJoining=DISABLED, depth=1, lqi=255]]]]

2019-01-04 17:40:27.622 [DEBUG] [gesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisAckMessageEvent [messageId=185]

2019-01-04 17:40:27.623 [DEBUG] [ngle.telegesis.ZigBeeDongleTelegesis] - Unhandled Telegesis Frame: TelegesisAckMessageEvent [messageId=185]

2019-01-04 17:40:27.623 [DEBUG] [iscovery.ZigBeeNodeServiceDiscoverer] - 000D6F00108DEAEA: Node SVC Discovery: ManagementLqiRequest complete [1 neighbors]

2019-01-04 17:40:27.626 [DEBUG] [com.zsmartsystems.zigbee.ZigBeeNode ] - 000D6F00108DEAEA: Neighbor table updated: [NeighborTable [extendedPanId=37FDDB4D24F01102, extendedAddress=000D6F000B9386E0, networkAddress=48425, deviceType=END_DEVICE, rxOnWhenIdle=RX_OFF, relationship=CHILD, permitJoining=DISABLED, depth=1, lqi=255]]

2019-01-04 17:40:27.630 [DEBUG] [iscovery.ZigBeeNodeServiceDiscoverer] - 000D6F00108DEAEA: Node SVC Discovery: request NEIGHBORS successful. Advanced to null.

2019-01-04 17:40:27.633 [DEBUG] [iscovery.ZigBeeNodeServiceDiscoverer] - 000D6F00108DEAEA: Node SVC Discovery: running

2019-01-04 17:40:27.637 [DEBUG] [iscovery.ZigBeeNodeServiceDiscoverer] - 000D6F00108DEAEA: Node SVC Discovery: complete

2019-01-04 17:40:27.639 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - 000D6F00108DEAEA: Node 0 update

2019-01-04 17:40:27.654 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: Start.

==> /var/log/openhab2/events.log <==

2019-01-04 17:40:27.662 [me.event.ThingUpdatedEvent] - Thing 'zigbee:coordinator_telegesis:04000B3A' has been updated.