Using Zigbee Binding 2.3.0 with CC2531

rwagnon · February 19, 2018, 10:08pm

I’ve spent several days trying to get the ZigBee Binding to work with a CC2531 using CC2531ZNP-Pro-Secure_Standard.hex. I have a Philips Hue white bulb, a Visonic Door Sensor/Temperature device, a Sylvania SmartPlug, and a SmartThings Multipurpose sensor. I can see the Door Sensor communicate and can see the Sylvaian SmartPlug send a lot of device specific information, but they do not appear in the Inbox.

I’ve read a lot of postings here and tried a lot of things suggested.

I’m including the log here:

==> /var/log/openhab2/openhab.log <==
2018-02-19 12:32:49.338 [DEBUG] [zigbee.internal.ZigBeeHandlerFactory] - Creating coordinator handler for zigbee:coordinator_cc2531:652922a1
2018-02-19 12:32:49.371 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - Creating ZigBee discovery service for zigbee:coordinator_cc2531:652922a1
2018-02-19 12:32:49.374 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - Activating ZigBee discovery service for zigbee:coordinator_cc2531:652922a1
2018-02-19 12:32:49.379 [DEBUG] [org.openhab.binding.zigbee          ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.config.discovery.DiscoveryService}={service.id=264, service.bundleid=203, service.scope=singleton} - org.openhab.binding.zigbee
2018-02-19 12:32:49.583 [DEBUG] [ndler.ZigBeeCoordinatorCC2531Handler] - Initializing ZigBee ZNP serial bridge handler.
2018-02-19 12:32:49.586 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Initializing ZigBee network [zigbee:coordinator_cc2531:652922a1].
2018-02-19 12:32:49.589 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Channel 11
2018-02-19 12:32:49.590 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - PANID 24509
2018-02-19 12:32:49.592 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - EPANID 352053169B0D3599
==> /var/log/openhab2/events.log <==
2018-02-19 12:32:49.598 [hingStatusInfoChangedEvent] - 'zigbee:coordinator_cc2531:652922a1' changed from UNINITIALIZED to INITIALIZING
==> /var/log/openhab2/openhab.log <==
2018-02-19 12:32:49.600 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Key 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
2018-02-19 12:32:49.611 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Key String 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
2018-02-19 12:32:49.617 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Key initialised B91A7EEC45E8D8862E0070A0266326BD
2018-02-19 12:32:49.618 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Key final array B91A7EEC45E8D8862E0070A0266326BD
2018-02-19 12:32:49.672 [DEBUG] [ndler.ZigBeeCoordinatorCC2531Handler] - ZigBee Coordinator ZNP opening Port:'/dev/ttyACM0' PAN:5fbd, Channel:11
2018-02-19 12:32:49.682 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Scheduling ZigBee start
==> /var/log/openhab2/events.log <==
2018-02-19 12:32:49.690 [hingStatusInfoChangedEvent] - 'zigbee:coordinator_cc2531:652922a1' changed from INITIALIZING to UNKNOWN
==> /var/log/openhab2/openhab.log <==
2018-02-19 12:32:49.702 [DEBUG] [zigbee.internal.ZigBeeHandlerFactory] - Creating coordinator handler for zigbee:coordinator_ember:0b9c5d9b
2018-02-19 12:32:49.707 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - Creating ZigBee discovery service for zigbee:coordinator_ember:0b9c5d9b
2018-02-19 12:32:49.709 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - Activating ZigBee discovery service for zigbee:coordinator_ember:0b9c5d9b
2018-02-19 12:32:49.713 [DEBUG] [org.openhab.binding.zigbee          ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.config.discovery.DiscoveryService}={service.id=265, service.bundleid=203, service.scope=singleton} - org.openhab.binding.zigbee
2018-02-19 12:32:49.718 [DEBUG] [org.openhab.binding.zigbee          ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.core.thing.binding.firmware.FirmwareUpdateHandler}={service.id=266, service.bundleid=203, service.scope=singleton} - org.openhab.binding.zigbee
==> /var/log/openhab2/openhab.log <==
2018-02-19 12:32:50.713 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - ZigBee network starting
2018-02-19 12:32:50.717 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Initialising ZigBee coordinator
2018-02-19 12:32:50.786 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Key initialise B91A7EEC45E8D8862E0070A0266326BD
2018-02-19 12:32:50.799 [DEBUG] [e.dongle.cc2531.ZigBeeDongleTiCc2531] - CC2531 transport initialize
2018-02-19 12:32:50.813 [DEBUG] [ing.zigbee.internal.ZigBeeSerialPort] - Connecting to serial port [/dev/ttyACM0] at 115200 baud, flow control FLOWCONTROL_OUT_NONE.
2018-02-19 12:32:50.848 [DEBUG] [ing.zigbee.internal.ZigBeeSerialPort] - Serial port [/dev/ttyACM0] is initialized.
2018-02-19 12:32:50.863 [DEBUG] [31.network.impl.CommandInterfaceImpl] - ->  SYS_RESET (Packet: subsystem=null, length=1, apiId=41 00, data=FE 01 41 00 01 41, checksum=41, error=false) 
2018-02-19 12:32:50.893 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - ZigBee network starting
2018-02-19 12:32:50.897 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Initialising ZigBee coordinator
2018-02-19 12:32:50.903 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Key initialise 4EE0021721C391C378AFE5F3E7BF75F6
2018-02-19 12:32:50.907 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - EZSP dongle initialize.
2018-02-19 12:32:50.910 [DEBUG] [ing.zigbee.internal.ZigBeeSerialPort] - Connecting to serial port [/dev/ttyUSB1] at 115200 baud, flow control FLOWCONTROL_OUT_RTSCTS.
2018-02-19 12:32:50.928 [DEBUG] [ing.zigbee.internal.ZigBeeSerialPort] - Serial port [/dev/ttyUSB1] is initialized.
2018-02-19 12:32:50.966 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - AshFrameHandler thread started
2018-02-19 12:32:50.974 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameRst []
2018-02-19 12:32:51.251 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP queue: 1
2018-02-19 12:32:51.263 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - Trying to send when not connected.
2018-02-19 12:32:52.502 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- SYS_RESET_RESPONSE (FE 06 41 80 00 02 00 02 06 03 C2)
2018-02-19 12:32:52.506 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=6, apiId=41 80, data=FE 06 41 80 00 02 00 02 06 03 C2, checksum=C2, error=false
2018-02-19 12:32:52.869 [DEBUG] [31.network.impl.CommandInterfaceImpl] - ->  SYS_VERSION (Packet: subsystem=null, length=0, apiId=21 02, data=FE 00 21 02 23, checksum=23, error=false) 
2018-02-19 12:32:52.995 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-  SYS_VERSION_RESPONSE (FE 05 61 02 02 00 02 06 03 63)
2018-02-19 12:32:52.998 [DEBUG] [.cc2531.network.ZigBeeNetworkManager] - CC2531 version is Software=2.6 Product=0 Hardware=3 Transport=2
2018-02-19 12:32:53.026 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - initResponse is JOINED
2018-02-19 12:32:53.028 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - initializeNetwork is false
2018-02-19 12:32:53.030 [DEBUG] [31.network.impl.CommandInterfaceImpl] - ->  ZB_GET_DEVICE_INFO (Packet: subsystem=null, length=1, apiId=26 06, data=FE 01 26 06 05 24, checksum=24, error=false) 
2018-02-19 12:32:53.145 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-  ZB_GET_DEVICE_INFO_RSP (FE 09 66 06 05 0B 00 00 00 00 00 00 00 67)
2018-02-19 12:32:53.147 [DEBUG] [31.network.impl.CommandInterfaceImpl] - ->  ZB_GET_DEVICE_INFO (Packet: subsystem=null, length=1, apiId=26 06, data=FE 01 26 06 06 27, checksum=27, error=false) 
2018-02-19 12:32:53.267 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-  ZB_GET_DEVICE_INFO_RSP (FE 09 66 06 06 BD 5F 00 00 00 00 00 00 8D)
2018-02-19 12:32:53.271 [DEBUG] [31.network.impl.CommandInterfaceImpl] - ->  ZB_GET_DEVICE_INFO (Packet: subsystem=null, length=1, apiId=26 06, data=FE 01 26 06 07 26, checksum=26, error=false) 
2018-02-19 12:32:53.419 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-  ZB_GET_DEVICE_INFO_RSP (FE 09 66 06 07 99 35 0D 9B 16 53 20 35 04)
2018-02-19 12:32:53.422 [DEBUG] [e.dongle.cc2531.ZigBeeDongleTiCc2531] - CC2531 transport startup
2018-02-19 12:32:53.425 [DEBUG] [.cc2531.network.ZigBeeNetworkManager] - Creating network as Coordinator
2018-02-19 12:32:53.428 [DEBUG] [31.network.impl.CommandInterfaceImpl] - ->  ZDO_MSG_CB_REGISTER (Packet: subsystem=null, length=2, apiId=25 3E, data=FE 02 25 3E FF FF 19, checksum=19, error=false) 
2018-02-19 12:32:53.537 [INFO ] [basic.internal.servlet.WebAppServlet] - Started Basic UI at /basicui/app
2018-02-19 12:32:53.548 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-  ZDO_MSG_CB_REGISTER_SRSP (FE 01 65 3E 00 5A)
2018-02-19 12:32:53.550 [DEBUG] [31.network.impl.CommandInterfaceImpl] - ->  ZB_WRITE_CONFIGURATION (Packet: subsystem=null, length=3, apiId=26 05, data=FE 03 26 05 8F 01 01 AF, checksum=AF, error=false) 
2018-02-19 12:32:53.668 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-  ZB_WRITE_CONFIGURATION_RSP (FE 01 66 05 00 62)
2018-02-19 12:32:53.670 [DEBUG] [31.network.impl.CommandInterfaceImpl] - ->  ZDO_STARTUP_FROM_APP (Packet: subsystem=null, length=2, apiId=25 40, data=FE 02 25 40 00 00 67, checksum=67, error=false) 
2018-02-19 12:32:53.675 [INFO ] [arthome.ui.paper.internal.PaperUIApp] - Started Paper UI at /paperui
2018-02-19 12:32:53.811 [INFO ] [panel.internal.HABPanelDashboardTile] - Started HABPanel at /habpanel
==> /var/log/openhab2/openhab.log <==
2018-02-19 12:32:54.428 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-  ZDO_STARTUP_FROM_APP_SRSP (FE 01 65 40 00 24)
2018-02-19 12:32:54.434 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- ZDO_STATE_CHANGE_IND (FE 01 45 C0 09 8D)
2018-02-19 12:32:54.435 [INFO ] [.cc2531.network.ZigBeeNetworkManager] - Initialized ZigBee network with existing network state.
2018-02-19 12:32:54.439 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: ZDO_STATE_CHANGE_IND{State=DEV_COORD_STARTED}
2018-02-19 12:32:54.444 [DEBUG] [e.dongle.cc2531.ZigBeeDongleTiCc2531] - Unhandled ZToolPacket type 0x45c0
2018-02-19 12:32:54.459 [DEBUG] [.cc2531.network.ZigBeeNetworkManager] - Started as ZigBee Coordinator
2018-02-19 12:32:54.489 [DEBUG] [31.network.impl.CommandInterfaceImpl] - ->  AF_REGISTER (Packet: subsystem=null, length=9, apiId=24 00, data=FE 09 24 00 01 04 01 00 00 00 00 00 00 29, checksum=29, error=false) 
2018-02-19 12:32:54.606 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-  AF_REGISTER_SRSP (FE 01 64 00 00 65)
2018-02-19 12:32:54.610 [DEBUG] [e.dongle.cc2531.ZigBeeDongleTiCc2531] - Registered endpoint 1 with profile: 260
==> /var/log/openhab2/events.log <==
2018-02-19 12:32:54.616 [hingStatusInfoChangedEvent] - 'zigbee:coordinator_cc2531:652922a1' changed from UNKNOWN to ONLINE
==> /var/log/openhab2/openhab.log <==
2018-02-19 12:32:54.618 [DEBUG] [31.network.impl.CommandInterfaceImpl] - ->  ZB_GET_DEVICE_INFO (Packet: subsystem=null, length=1, apiId=26 06, data=FE 01 26 06 05 24, checksum=24, error=false) 
2018-02-19 12:32:54.620 [DEBUG] [bee.internal.ZigBeeNetworkDiscoverer] - 0: Scheduling node discovery
2018-02-19 12:32:54.630 [DEBUG] [bee.internal.ZigBeeNetworkDiscoverer] - 0: Starting node discovery
2018-02-19 12:32:54.662 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: IeeeAddressRequest [0/0 -> 0/0, cluster=0001, TID=00, nwkAddrOfInterest=0, requestType=1, startIndex=0]
2018-02-19 12:32:54.722 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=1, addressMode=DEVICE, radius=31, sequence=0, payload=00 00 00 01 00]
2018-02-19 12:32:54.727 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-  ZB_GET_DEVICE_INFO_RSP (FE 09 66 06 05 0B 06 00 FF FE 01 64 00 05)
2018-02-19 12:32:54.730 [DEBUG] [31.network.impl.CommandInterfaceImpl] - ->  AF_DATA_REQUEST (Packet: subsystem=null, length=15, apiId=24 01, data=FE 0F 24 01 00 00 00 00 01 00 00 30 1F 05 00 00 00 01 00 00, checksum=00, error=false) 
2018-02-19 12:32:54.863 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-  AF_DATA_SRSP (FE 01 64 01 00 64)
2018-02-19 12:32:54.866 [DEBUG] [31.network.impl.CommandInterfaceImpl] - ->  ZB_GET_DEVICE_INFO (Packet: subsystem=null, length=1, apiId=26 06, data=FE 01 26 06 07 26, checksum=26, error=false) 
2018-02-19 12:32:54.866 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- ZToolPacket (FE 0D 45 81 00 10 7E 0D 0E 00 4B 12 00 00 00 00 00 FD)
2018-02-19 12:32:54.869 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=13, apiId=45 81, data=FE 0D 45 81 00 10 7E 0D 0E 00 4B 12 00 00 00 00 00 FD, checksum=FD, error=false
2018-02-19 12:32:54.947 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=32769, addressMode=null, radius=0, sequence=0, payload=00 00 10 7E 0D 0E 00 4B 12 00 00 00 00 00]
2018-02-19 12:32:54.959 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: IeeeAddressResponse [0/0 -> 0/0, cluster=8001, TID=NULL, status=SUCCESS, ieeeAddrRemoteDev=00124B000E0D7E10, nwkAddrRemoteDev=0, startIndex=0, nwkAddrAssocDevList=[]]
2018-02-19 12:32:54.969 [DEBUG] [bee.internal.ZigBeeNetworkDiscoverer] - 0: Ieee Address returned IeeeAddressResponse [0/0 -> 0/0, cluster=8001, TID=NULL, status=SUCCESS, ieeeAddrRemoteDev=00124B000E0D7E10, nwkAddrRemoteDev=0, startIndex=0, nwkAddrAssocDevList=[]]
2018-02-19 12:32:54.974 [DEBUG] [com.zsmartsystems.zigbee.ZigBeeNode ] - 00124B000E0D7E10: Associated devices table unchanged
2018-02-19 12:32:54.979 [DEBUG] [bee.internal.ZigBeeNetworkDiscoverer] - 0: Discovery request IEEE_ADDRESS successful. Advanced to NODE_DESCRIPTOR.
2018-02-19 12:32:54.984 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- ZToolPacket (FE 15 45 FF 00 00 00 01 80 00 00 00 00 00 10 7E 0D 0E 00 4B 12 00 00 00 00 1A)
2018-02-19 12:32:54.987 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=21, apiId=45 FF, data=FE 15 45 FF 00 00 00 01 80 00 00 00 00 00 10 7E 0D 0E 00 4B 12 00 00 00 00 1A, checksum=1A, error=false
2018-02-19 12:32:54.982 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: NodeDescriptorRequest [0/0 -> 0/0, cluster=0002, TID=01, nwkAddrOfInterest=0]
2018-02-19 12:32:54.992 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=2, addressMode=DEVICE, radius=31, sequence=1, payload=00 00 00]
2018-02-19 12:32:54.995 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=32769, addressMode=null, radius=0, sequence=0, payload=00 00 10 7E 0D 0E 00 4B 12 00 00 00 00]
2018-02-19 12:32:54.998 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: IeeeAddressResponse [0/0 -> 0/0, cluster=8001, TID=NULL, status=SUCCESS, ieeeAddrRemoteDev=00124B000E0D7E10, nwkAddrRemoteDev=0, startIndex=null, nwkAddrAssocDevList=[]]
2018-02-19 12:32:55.009 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-  ZB_GET_DEVICE_INFO_RSP (FE 09 66 06 07 99 35 0D 9B 16 53 20 35 04)
2018-02-19 12:32:55.012 [DEBUG] [31.network.impl.CommandInterfaceImpl] - ->  AF_DATA_REQUEST (Packet: subsystem=null, length=13, apiId=24 01, data=FE 0D 24 01 00 00 00 00 02 00 01 30 1F 03 00 00 00 07, checksum=07, error=false) 
2018-02-19 12:32:55.013 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Initialise done....... 11  24509  352053169B0D3599
==> /var/log/openhab2/events.log <==
2018-02-19 12:32:55.031 [me.event.ThingUpdatedEvent] - Thing 'zigbee:coordinator_cc2531:652922a1' has been updated.
==> /var/log/openhab2/openhab.log <==
2018-02-19 12:32:55.566 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-  AF_DATA_SRSP (FE 01 64 01 00 64)
2018-02-19 12:32:55.581 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- ZToolPacket (FE 12 45 82 00 00 00 00 00 00 40 8F 00 00 50 A0 00 01 00 A0 00 00 4B)
2018-02-19 12:32:55.579 [DEBUG] [31.network.impl.CommandInterfaceImpl] - ->  ZB_GET_DEVICE_INFO (Packet: subsystem=null, length=1, apiId=26 06, data=FE 01 26 06 07 26, checksum=26, error=false) 
2018-02-19 12:32:55.584 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=18, apiId=45 82, data=FE 12 45 82 00 00 00 00 00 00 40 8F 00 00 50 A0 00 01 00 A0 00 00 4B, checksum=4B, error=false
2018-02-19 12:32:55.604 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=32770, addressMode=null, radius=0, sequence=0, payload=00 00 00 00 00 40 8F 00 00 50 A0 00 01 00 A0 00 00]
2018-02-19 12:32:55.668 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: NodeDescriptorResponse [0/0 -> 0/0, cluster=8002, TID=NULL, status=SUCCESS, nwkAddrOfInterest=0, nodeDescriptor=NodeDescriptor [apsFlags=0, bufferSize=80, complexDescriptorAvailable=false, manufacturerCode=0, logicalType=COORDINATOR, serverCapabilities=[PRIMARY_TRUST_CENTER], incomingTransferSize=160, outgoingTransferSize=160, userDescriptorAvailable=false, frequencyBands=[FREQ_2400_MHZ], macCapabilities=[RECEIVER_ON_WHEN_IDLE, MAINS_POWER, ALTERNATIVE_PAN, FULL_FUNCTION_DEVICE], extendedEndpointListAvailable=false, extendedSimpleDescriptorListAvailable=false]]
2018-02-19 12:32:55.672 [DEBUG] [bee.internal.ZigBeeNetworkDiscoverer] - 0: Node Descriptor returned NodeDescriptorResponse [0/0 -> 0/0, cluster=8002, TID=NULL, status=SUCCESS, nwkAddrOfInterest=0, nodeDescriptor=NodeDescriptor [apsFlags=0, bufferSize=80, complexDescriptorAvailable=false, manufacturerCode=0, logicalType=COORDINATOR, serverCapabilities=[PRIMARY_TRUST_CENTER], incomingTransferSize=160, outgoingTransferSize=160, userDescriptorAvailable=false, frequencyBands=[FREQ_2400_MHZ], macCapabilities=[RECEIVER_ON_WHEN_IDLE, MAINS_POWER, ALTERNATIVE_PAN, FULL_FUNCTION_DEVICE], extendedEndpointListAvailable=false, extendedSimpleDescriptorListAvailable=false]]
2018-02-19 12:32:55.675 [DEBUG] [bee.internal.ZigBeeNetworkDiscoverer] - 0: Discovery request NODE_DESCRIPTOR successful. Advanced to POWER_DESCRIPTOR.
2018-02-19 12:32:55.683 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: PowerDescriptorRequest [0/0 -> 0/0, cluster=0003, TID=02, nwkAddrOfInterest=0]
2018-02-19 12:32:55.685 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=3, addressMode=DEVICE, radius=31, sequence=2, payload=00 00 00]
2018-02-19 12:32:55.692 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- ZToolPacket (FE 19 45 FF 00 00 00 02 80 00 00 00 00 00 00 00 00 40 8F 00 00 50 A0 00 01 00 A0 00 00 BF)
2018-02-19 12:32:55.693 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=25, apiId=45 FF, data=FE 19 45 FF 00 00 00 02 80 00 00 00 00 00 00 00 00 40 8F 00 00 50 A0 00 01 00 A0 00 00 BF, checksum=BF, error=false
2018-02-19 12:32:55.696 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=32770, addressMode=null, radius=0, sequence=0, payload=00 00 00 00 00 40 8F 00 00 50 A0 00 01 00 A0 00 00]
2018-02-19 12:32:55.697 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: NodeDescriptorResponse [0/0 -> 0/0, cluster=8002, TID=NULL, status=SUCCESS, nwkAddrOfInterest=0, nodeDescriptor=NodeDescriptor [apsFlags=0, bufferSize=80, complexDescriptorAvailable=false, manufacturerCode=0, logicalType=COORDINATOR, serverCapabilities=[PRIMARY_TRUST_CENTER], incomingTransferSize=160, outgoingTransferSize=160, userDescriptorAvailable=false, frequencyBands=[FREQ_2400_MHZ], macCapabilities=[RECEIVER_ON_WHEN_IDLE, MAINS_POWER, ALTERNATIVE_PAN, FULL_FUNCTION_DEVICE], extendedEndpointListAvailable=false, extendedSimpleDescriptorListAvailable=false]]
2018-02-19 12:32:55.701 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-  ZB_GET_DEVICE_INFO_RSP (FE 09 66 06 07 99 35 0D 9B 16 53 20 35 04)
2018-02-19 12:32:55.705 [DEBUG] [31.network.impl.CommandInterfaceImpl] - ->  AF_DATA_REQUEST (Packet: subsystem=null, length=13, apiId=24 01, data=FE 0D 24 01 00 00 00 00 03 00 02 30 1F 03 00 00 00 05, checksum=05, error=false) 
2018-02-19 12:32:55.705 [DEBUG] [com.zsmartsystems.zigbee.ZigBeeNode ] - 352053169B0D3599: ClusterMatcher starting
2018-02-19 12:32:55.832 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-  AF_DATA_SRSP (FE 01 64 01 00 64)
2018-02-19 12:32:55.838 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- ZToolPacket (FE 07 45 83 00 00 00 00 00 10 C1 10)
2018-02-19 12:32:55.838 [DEBUG] [31.network.impl.CommandInterfaceImpl] - ->  ZB_GET_DEVICE_INFO (Packet: subsystem=null, length=1, apiId=26 06, data=FE 01 26 06 07 26, checksum=26, error=false) 
2018-02-19 12:32:55.840 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=7, apiId=45 83, data=FE 07 45 83 00 00 00 00 00 10 C1 10, checksum=10, error=false
2018-02-19 12:32:55.844 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=32771, addressMode=null, radius=0, sequence=0, payload=00 00 00 00 10 C1]
2018-02-19 12:32:55.850 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: PowerDescriptorResponse [0/0 -> 0/0, cluster=8003, TID=NULL, status=SUCCESS, nwkAddrOfInterest=0, powerDescriptor=RECEIVER_ON_IDLE, [MAINS], MAINS, FULL]
2018-02-19 12:32:55.853 [DEBUG] [bee.internal.ZigBeeNetworkDiscoverer] - 0: Power Descriptor returned PowerDescriptorResponse [0/0 -> 0/0, cluster=8003, TID=NULL, status=SUCCESS, nwkAddrOfInterest=0, powerDescriptor=RECEIVER_ON_IDLE, [MAINS], MAINS, FULL]
2018-02-19 12:32:55.854 [DEBUG] [bee.internal.ZigBeeNetworkDiscoverer] - 0: Discovery request POWER_DESCRIPTOR successful. Advanced to ACTIVE_ENDPOINTS.
2018-02-19 12:32:55.857 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ActiveEndpointsRequest [0/0 -> 0/0, cluster=0005, TID=03, nwkAddrOfInterest=0]
2018-02-19 12:32:55.859 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=5, addressMode=DEVICE, radius=31, sequence=3, payload=00 00 00]
2018-02-19 12:32:55.953 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- ZToolPacket (FE 0E 45 FF 00 00 00 03 80 00 00 00 00 00 00 00 10 C1 E6)
2018-02-19 12:32:55.955 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=14, apiId=45 FF, data=FE 0E 45 FF 00 00 00 03 80 00 00 00 00 00 00 00 10 C1 E6, checksum=E6, error=false
2018-02-19 12:32:55.958 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=32771, addressMode=null, radius=0, sequence=0, payload=00 00 00 00 10 C1]
2018-02-19 12:32:55.960 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: PowerDescriptorResponse [0/0 -> 0/0, cluster=8003, TID=NULL, status=SUCCESS, nwkAddrOfInterest=0, powerDescriptor=RECEIVER_ON_IDLE, [MAINS], MAINS, FULL]
2018-02-19 12:32:55.964 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-  ZB_GET_DEVICE_INFO_RSP (FE 09 66 06 07 99 35 0D 9B 16 53 20 35 04)
2018-02-19 12:32:55.967 [DEBUG] [31.network.impl.CommandInterfaceImpl] - ->  AF_DATA_REQUEST (Packet: subsystem=null, length=13, apiId=24 01, data=FE 0D 24 01 00 00 00 00 05 00 03 30 1F 03 00 00 00 02, checksum=02, error=false) 
2018-02-19 12:32:55.967 [DEBUG] [com.zsmartsystems.zigbee.ZigBeeNode ] - 352053169B0D3599: ClusterMatcher adding cluster 1280
2018-02-19 12:32:56.094 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-  AF_DATA_SRSP (FE 01 64 01 00 64)
2018-02-19 12:32:56.096 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- ZToolPacket (FE 07 45 85 00 00 00 00 00 01 01 C7)
2018-02-19 12:32:56.098 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=7, apiId=45 85, data=FE 07 45 85 00 00 00 00 00 01 01 C7, checksum=C7, error=false
2018-02-19 12:32:56.101 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=32773, addressMode=null, radius=0, sequence=0, payload=00 00 00 00 01 01]
2018-02-19 12:32:56.104 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ActiveEndpointsResponse [0/0 -> 0/0, cluster=8005, TID=NULL, status=SUCCESS, nwkAddrOfInterest=0, activeEpList=[1]]
2018-02-19 12:32:56.107 [DEBUG] [bee.internal.ZigBeeNetworkDiscoverer] - 0: Active Endpoints returned ActiveEndpointsResponse [0/0 -> 0/0, cluster=8005, TID=NULL, status=SUCCESS, nwkAddrOfInterest=0, activeEpList=[1]]
2018-02-19 12:32:56.111 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- ZToolPacket (FE 0E 45 FF 00 00 00 05 80 00 00 00 00 00 00 00 01 01 31)
2018-02-19 12:32:56.112 [DEBUG] [bee.internal.ZigBeeNetworkDiscoverer] - 0/1: Scheduling endpoint discovery
2018-02-19 12:32:56.113 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=14, apiId=45 FF, data=FE 0E 45 FF 00 00 00 05 80 00 00 00 00 00 00 00 01 01 31, checksum=31, error=false
2018-02-19 12:32:56.115 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=32773, addressMode=null, radius=0, sequence=0, payload=00 00 00 00 01 01]
2018-02-19 12:32:56.118 [DEBUG] [bee.internal.ZigBeeNetworkDiscoverer] - 0: Discovery request ACTIVE_ENDPOINTS successful. Advanced to DISCOVERY_END.
2018-02-19 12:32:56.118 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ActiveEndpointsResponse [0/0 -> 0/0, cluster=8005, TID=NULL, status=SUCCESS, nwkAddrOfInterest=0, activeEpList=[1]]
2018-02-19 12:32:56.122 [DEBUG] [bee.internal.ZigBeeNetworkDiscoverer] - 0: Ending node discovery
2018-02-19 12:32:56.122 [DEBUG] [bee.internal.ZigBeeNetworkDiscoverer] - 0/1: Starting endpoint discovery
2018-02-19 12:32:56.125 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: SimpleDescriptorRequest [0/0 -> 0/0, cluster=0004, TID=04, nwkAddrOfInterest=0, endpoint=1]
2018-02-19 12:32:56.128 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=4, addressMode=DEVICE, radius=31, sequence=4, payload=00 00 00 01]
2018-02-19 12:32:56.130 [DEBUG] [31.network.impl.CommandInterfaceImpl] - ->  AF_DATA_REQUEST (Packet: subsystem=null, length=14, apiId=24 01, data=FE 0E 24 01 00 00 00 00 04 00 04 30 1F 04 00 00 00 01 01, checksum=01, error=false) 
2018-02-19 12:32:56.261 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-  AF_DATA_SRSP (FE 01 64 01 00 64)
2018-02-19 12:32:56.263 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- ZToolPacket (FE 0E 45 84 00 00 00 00 00 08 01 04 01 00 00 00 00 00 C3)
2018-02-19 12:32:56.265 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=14, apiId=45 84, data=FE 0E 45 84 00 00 00 00 00 08 01 04 01 00 00 00 00 00 C3, checksum=C3, error=false
2018-02-19 12:32:56.269 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=32772, addressMode=null, radius=0, sequence=0, payload=00 00 00 00 08 01 04 01 00 00 00 00 00]
2018-02-19 12:32:56.273 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: SimpleDescriptorResponse [0/0 -> 0/0, cluster=8004, TID=NULL, status=SUCCESS, nwkAddrOfInterest=0, length=8, simpleDescriptor=SimpleDescriptor [endpoint=1, profileId=0104, deviceId=0, deviceVersion=0, inputClusterList=[], outputClusterList=[]]]
2018-02-19 12:32:56.279 [DEBUG] [bee.internal.ZigBeeNetworkDiscoverer] - 0/1: Simple Descriptor returned SimpleDescriptorResponse [0/0 -> 0/0, cluster=8004, TID=NULL, status=SUCCESS, nwkAddrOfInterest=0, length=8, simpleDescriptor=SimpleDescriptor [endpoint=1, profileId=0104, deviceId=0, deviceVersion=0, inputClusterList=[], outputClusterList=[]]]
2018-02-19 12:32:56.280 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- ZToolPacket (FE 15 45 FF 00 00 00 04 80 00 00 00 00 00 00 00 08 01 04 01 00 00 00 00 00 27)
2018-02-19 12:32:56.290 [DEBUG] [.zsmartsystems.zigbee.ZigBeeEndpoint] - 0/1: Setting input clusters []
2018-02-19 12:32:56.290 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=21, apiId=45 FF, data=FE 15 45 FF 00 00 00 04 80 00 00 00 00 00 00 00 08 01 04 01 00 00 00 00 00 27, checksum=27, error=false
2018-02-19 12:32:56.292 [DEBUG] [.zsmartsystems.zigbee.ZigBeeEndpoint] - 0/1: Setting output clusters []
2018-02-19 12:32:56.293 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=32772, addressMode=null, radius=0, sequence=0, payload=00 00 00 00 08 01 04 01 00 00 00 00 00]
2018-02-19 12:32:56.299 [DEBUG] [bee.internal.ZigBeeNetworkDiscoverer] - 0: Discovery has completed all endpoints
2018-02-19 12:32:56.300 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: SimpleDescriptorResponse [0/0 -> 0/0, cluster=8004, TID=NULL, status=SUCCESS, nwkAddrOfInterest=0, length=8, simpleDescriptor=SimpleDescriptor [endpoint=1, profileId=0104, deviceId=0, deviceVersion=0, inputClusterList=[], outputClusterList=[]]]
2018-02-19 12:32:56.303 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - 00124B000E0D7E10: Node 0 is added to the network
2018-02-19 12:32:56.412 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: done.
2018-02-19 12:32:56.418 [DEBUG] [bee.internal.ZigBeeNetworkDiscoverer] - 0/1: Ending endpoint discovery

rwagnon · February 19, 2018, 10:16pm

Note this section quoted from above regarding POWER_DESCRIPTOR:

018-02-19 12:32:55.566 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-  AF_DATA_SRSP (FE 01 64 01 00 64)
2018-02-19 12:32:55.581 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- ZToolPacket (FE 12 45 82 00 00 00 00 00 00 40 8F 00 00 50 A0 00 01 00 A0 00 00 4B)
2018-02-19 12:32:55.579 [DEBUG] [31.network.impl.CommandInterfaceImpl] - ->  ZB_GET_DEVICE_INFO (Packet: subsystem=null, length=1, apiId=26 06, data=FE 01 26 06 07 26, checksum=26, error=false) 
2018-02-19 12:32:55.584 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=18, apiId=45 82, data=FE 12 45 82 00 00 00 00 00 00 40 8F 00 00 50 A0 00 01 00 A0 00 00 4B, checksum=4B, error=false
2018-02-19 12:32:55.604 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=32770, addressMode=null, radius=0, sequence=0, payload=00 00 00 00 00 40 8F 00 00 50 A0 00 01 00 A0 00 00]
2018-02-19 12:32:55.668 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: NodeDescriptorResponse [0/0 -> 0/0, cluster=8002, TID=NULL, status=SUCCESS, nwkAddrOfInterest=0, nodeDescriptor=NodeDescriptor [apsFlags=0, bufferSize=80, complexDescriptorAvailable=false, manufacturerCode=0, logicalType=COORDINATOR, serverCapabilities=[PRIMARY_TRUST_CENTER], incomingTransferSize=160, outgoingTransferSize=160, userDescriptorAvailable=false, frequencyBands=[FREQ_2400_MHZ], macCapabilities=[RECEIVER_ON_WHEN_IDLE, MAINS_POWER, ALTERNATIVE_PAN, FULL_FUNCTION_DEVICE], extendedEndpointListAvailable=false, extendedSimpleDescriptorListAvailable=false]]
2018-02-19 12:32:55.672 [DEBUG] [bee.internal.ZigBeeNetworkDiscoverer] - 0: Node Descriptor returned NodeDescriptorResponse [0/0 -> 0/0, cluster=8002, TID=NULL, status=SUCCESS, nwkAddrOfInterest=0, nodeDescriptor=NodeDescriptor [apsFlags=0, bufferSize=80, complexDescriptorAvailable=false, manufacturerCode=0, logicalType=COORDINATOR, serverCapabilities=[PRIMARY_TRUST_CENTER], incomingTransferSize=160, outgoingTransferSize=160, userDescriptorAvailable=false, frequencyBands=[FREQ_2400_MHZ], macCapabilities=[RECEIVER_ON_WHEN_IDLE, MAINS_POWER, ALTERNATIVE_PAN, FULL_FUNCTION_DEVICE], extendedEndpointListAvailable=false, extendedSimpleDescriptorListAvailable=false]]
2018-02-19 12:32:55.675 [DEBUG] [bee.internal.ZigBeeNetworkDiscoverer] - 0: Discovery request NODE_DESCRIPTOR successful. Advanced to POWER_DESCRIPTOR.

rwagnon · February 19, 2018, 10:19pm

Here is another snippet from a different log that references the MAC of the Visonic Door Sensor:

<-- ZToolPacket (FE 0C 45 CA EE 5D 39 13 11 0B 00 6F 0D 00 00 00 62)
2018-02-19 12:34:36.093 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=12, apiId=45 CA, data=FE 0C 45 CA EE 5D 39 13 11 0B 00 6F 0D 00 00 00 62, checksum=62, error=false
2018-02-19 12:34:36.095 [DEBUG] [e.dongle.cc2531.ZigBeeDongleTiCc2531] - Unhandled ZToolPacket type 0x45ca

chris · February 19, 2018, 11:13pm

There’s no devices joining from this log - other than that I don’t see any problems in the log. There’s also no sign of you putting the binding into join mode - are you starting a scan?

rwagnon · February 19, 2018, 11:24pm

I went here:

Chris, thank you for your work on this project. It looks like a great deal of work.

chris · February 19, 2018, 11:36pm

That looks correct - maybe the log just doesn’t cover this - I don’t know really. All the communication in the log is only with the coordinator.

Note that there is a log viewer here (you need to register on the site to use it though). You might like to use this to view the logs to see what’s going on.

rwagnon · February 20, 2018, 12:59am

The log tool is interesting. I don’t see any join entries in the log, but I do see neighbor and route entries:

I don’t know what to look for.

I also get this, does is suggest it sees nodes?

rwagnon · February 20, 2018, 1:01am

I learned that I get this if I filter by BROADCAST_ROUTERS_AND_COORD

rwagnon · February 21, 2018, 4:31am

Chris,

I see what you mean about the missing “permit join”. I’ve attached a log that includes that section:

2018-02-20 22:26:07.409 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - Starting ZigBee scan for zigbee:coordinator_cc2531:652922a1
2018-02-20 22:26:07.415 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - Permit join to 65532/0 for 60 seconds.
2018-02-20 22:26:07.420 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ManagementPermitJoiningRequest [0/0 -> 65532/0, cluster=0036, TID=09, permitDuration=60, tcSignificance=true]
2018-02-20 22:26:07.425 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=65532/0, profile=0000, cluster=54, addressMode=DEVICE, radius=31, sequence=9, payload=00 3C 01]
2018-02-20 22:26:07.430 [DEBUG] [31.network.impl.CommandInterfaceImpl] - ->  AF_DATA_REQUEST (Packet: subsystem=null, length=13, apiId=24 01, data=FE 0D 24 01 FC FF 00 00 36 00 09 30 1F 03 00 3C 01 05, checksum=05, error=false) 
2018-02-20 22:26:07.564 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-  AF_DATA_SRSP (FE 01 64 01 00 64)
2018-02-20 22:26:07.566 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ManagementPermitJoiningRequest [0/0 -> 0/0, cluster=0036, TID=0A, permitDuration=60, tcSignificance=true]
2018-02-20 22:26:07.569 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=54, addressMode=DEVICE, radius=31, sequence=10, payload=00 3C 01]
2018-02-20 22:26:07.572 [DEBUG] [31.network.impl.CommandInterfaceImpl] - ->  AF_DATA_REQUEST (Packet: subsystem=null, length=13, apiId=24 01, data=FE 0D 24 01 00 00 00 00 36 00 0A 30 1F 03 00 3C 01 05, checksum=05, error=false) 
2018-02-20 22:26:07.702 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-  AF_DATA_SRSP (FE 01 64 01 00 64)
2018-02-20 22:26:07.703 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- ZToolPacket (FE 01 45 CB 3C B3)
2018-02-20 22:26:07.705 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=1, apiId=45 CB, data=FE 01 45 CB 3C B3, checksum=B3, error=false
2018-02-20 22:26:07.707 [DEBUG] [e.dongle.cc2531.ZigBeeDongleTiCc2531] - Unhandled ZToolPacket type 0x45cb
2018-02-20 22:26:07.709 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- ZToolPacket (FE 03 45 B6 00 00 00 F0)
2018-02-20 22:26:07.710 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=3, apiId=45 B6, data=FE 03 45 B6 00 00 00 F0, checksum=F0, error=false
2018-02-20 22:26:07.712 [DEBUG] [e.dongle.cc2531.ZigBeeDongleTiCc2531] - Unhandled ZToolPacket type 0x45b6
2018-02-20 22:26:07.714 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- ZToolPacket (FE 0A 45 FF 00 00 00 36 80 00 00 00 00 00 06)
2018-02-20 22:26:07.717 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=10, apiId=45 FF, data=FE 0A 45 FF 00 00 00 36 80 00 00 00 00 00 06, checksum=06, error=false
2018-02-20 22:26:07.719 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=32822, addressMode=null, radius=0, sequence=0, payload=00 00]
2018-02-20 22:26:07.721 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ManagementPermitJoiningResponse [0/0 -> 0/0, cluster=8036, TID=NULL, status=SUCCESS]
2018-02-20 22:27:07.695 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- ZToolPacket (FE 01 45 CB 00 8F)
2018-02-20 22:27:07.699 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=1, apiId=45 CB, data=FE 01 45 CB 00 8F, checksum=8F, error=false
2018-02-20 22:27:07.701 [DEBUG] [e.dongle.cc2531.ZigBeeDongleTiCc2531] - Unhandled ZToolPacket type 0x45cb
2018-02-20 22:27:09.063 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - Starting ZigBee scan for zigbee:coordinator_cc2531:652922a1
2018-02-20 22:27:09.072 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - Permit join to 65532/0 for 60 seconds.
2018-02-20 22:27:09.087 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ManagementPermitJoiningRequest [0/0 -> 65532/0, cluster=0036, TID=0B, permitDuration=60, tcSignificance=true]
2018-02-20 22:27:09.093 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=65532/0, profile=0000, cluster=54, addressMode=DEVICE, radius=31, sequence=11, payload=00 3C 01]
2018-02-20 22:27:09.116 [DEBUG] [31.network.impl.CommandInterfaceImpl] - ->  AF_DATA_REQUEST (Packet: subsystem=null, length=13, apiId=24 01, data=FE 0D 24 01 FC FF 00 00 36 00 0B 30 1F 03 00 3C 01 07, checksum=07, error=false) 
2018-02-20 22:27:09.245 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-  AF_DATA_SRSP (FE 01 64 01 00 64)
2018-02-20 22:27:09.247 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ManagementPermitJoiningRequest [0/0 -> 0/0, cluster=0036, TID=0C, permitDuration=60, tcSignificance=true]
2018-02-20 22:27:09.251 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=54, addressMode=DEVICE, radius=31, sequence=12, payload=00 3C 01]
2018-02-20 22:27:09.254 [DEBUG] [31.network.impl.CommandInterfaceImpl] - ->  AF_DATA_REQUEST (Packet: subsystem=null, length=13, apiId=24 01, data=FE 0D 24 01 00 00 00 00 36 00 0C 30 1F 03 00 3C 01 03, checksum=03, error=false) 
2018-02-20 22:27:09.389 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-  AF_DATA_SRSP (FE 01 64 01 00 64)
2018-02-20 22:27:09.390 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- ZToolPacket (FE 01 45 CB 3C B3)
2018-02-20 22:27:09.392 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=1, apiId=45 CB, data=FE 01 45 CB 3C B3, checksum=B3, error=false
2018-02-20 22:27:09.393 [DEBUG] [e.dongle.cc2531.ZigBeeDongleTiCc2531] - Unhandled ZToolPacket type 0x45cb
2018-02-20 22:27:09.395 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- ZToolPacket (FE 03 45 B6 00 00 00 F0)
2018-02-20 22:27:09.397 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=3, apiId=45 B6, data=FE 03 45 B6 00 00 00 F0, checksum=F0, error=false
2018-02-20 22:27:09.398 [DEBUG] [e.dongle.cc2531.ZigBeeDongleTiCc2531] - Unhandled ZToolPacket type 0x45b6
2018-02-20 22:27:09.400 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- ZToolPacket (FE 0A 45 FF 00 00 00 36 80 00 00 00 00 00 06)
2018-02-20 22:27:09.402 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=10, apiId=45 FF, data=FE 0A 45 FF 00 00 00 36 80 00 00 00 00 00 06, checksum=06, error=false
2018-02-20 22:27:09.404 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=32822, addressMode=null, radius=0, sequence=0, payload=00 00]
2018-02-20 22:27:09.406 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ManagementPermitJoiningResponse [0/0 -> 0/0, cluster=8036, TID=NULL, status=SUCCESS]
2018-02-20 22:27:42.592 [DEBUG] [tems.zigbee.ZigBeeNetworkMeshMonitor] - Starting mesh update task with interval of 300 seconds
2018-02-20 22:27:52.608 [DEBUG] [tems.zigbee.ZigBeeNetworkMeshMonitor] - 0: Starting mesh update
2018-02-20 22:27:52.613 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ManagementLqiRequest [0/0 -> 0/0, cluster=0031, TID=0D, startIndex=0]
2018-02-20 22:27:52.617 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=49, addressMode=DEVICE, radius=31, sequence=13, payload=00 00]
2018-02-20 22:27:52.619 [DEBUG] [31.network.impl.CommandInterfaceImpl] - ->  AF_DATA_REQUEST (Packet: subsystem=null, length=12, apiId=24 01, data=FE 0C 24 01 00 00 00 00 31 00 0D 30 1F 02 00 00 38, checksum=38, error=false) 
2018-02-20 22:27:52.749 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-  AF_DATA_SRSP (FE 01 64 01 00 64)
2018-02-20 22:27:52.752 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- ZToolPacket (FE 06 45 B1 00 00 00 00 00 00 F2)
2018-02-20 22:27:52.754 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=6, apiId=45 B1, data=FE 06 45 B1 00 00 00 00 00 00 F2, checksum=F2, error=false
2018-02-20 22:27:52.760 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=32817, addressMode=null, radius=0, sequence=0, payload=00 00 00 00 00]
2018-02-20 22:27:52.764 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ManagementLqiResponse [0/0 -> 0/0, cluster=8031, TID=NULL, status=SUCCESS, neighborTableEntries=0, startIndex=0, neighborTableList=[]]
2018-02-20 22:27:52.773 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- ZToolPacket (FE 0D 45 FF 00 00 00 31 80 00 00 00 00 00 00 00 00 06)
2018-02-20 22:27:52.774 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: IeeeAddressRequest [0/0 -> 0/0, cluster=0001, TID=0E, nwkAddrOfInterest=0, requestType=1, startIndex=0]
2018-02-20 22:27:52.776 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=13, apiId=45 FF, data=FE 0D 45 FF 00 00 00 31 80 00 00 00 00 00 00 00 00 06, checksum=06, error=false
2018-02-20 22:27:52.777 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=1, addressMode=DEVICE, radius=31, sequence=14, payload=00 00 00 01 00]
2018-02-20 22:27:52.779 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=32817, addressMode=null, radius=0, sequence=0, payload=00 00 00 00 00]
2018-02-20 22:27:52.780 [DEBUG] [31.network.impl.CommandInterfaceImpl] - ->  AF_DATA_REQUEST (Packet: subsystem=null, length=15, apiId=24 01, data=FE 0F 24 01 00 00 00 00 01 00 0E 30 1F 05 00 00 00 01 00 0E, checksum=0E, error=false) 
2018-02-20 22:27:52.785 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ManagementLqiResponse [0/0 -> 0/0, cluster=8031, TID=NULL, status=SUCCESS, neighborTableEntries=0, startIndex=0, neighborTableList=[]]
2018-02-20 22:27:52.914 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-  AF_DATA_SRSP (FE 01 64 01 00 64)
2018-02-20 22:27:52.916 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- ZToolPacket (FE 0D 45 81 00 10 7E 0D 0E 00 4B 12 00 00 00 00 00 FD)
2018-02-20 22:27:52.918 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=13, apiId=45 81, data=FE 0D 45 81 00 10 7E 0D 0E 00 4B 12 00 00 00 00 00 FD, checksum=FD, error=false
2018-02-20 22:27:52.920 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=32769, addressMode=null, radius=0, sequence=0, payload=00 00 10 7E 0D 0E 00 4B 12 00 00 00 00 00]
2018-02-20 22:27:52.922 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: IeeeAddressResponse [0/0 -> 0/0, cluster=8001, TID=NULL, status=SUCCESS, ieeeAddrRemoteDev=00124B000E0D7E10, nwkAddrRemoteDev=0, startIndex=0, nwkAddrAssocDevList=[]]
2018-02-20 22:27:52.925 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- ZToolPacket (FE 15 45 FF 00 00 00 01 80 00 00 00 00 00 10 7E 0D 0E 00 4B 12 00 00 00 00 1A)
2018-02-20 22:27:52.925 [DEBUG] [com.zsmartsystems.zigbee.ZigBeeNode ] - 00124B000E0D7E10: Associated devices table unchanged
2018-02-20 22:27:52.927 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=21, apiId=45 FF, data=FE 15 45 FF 00 00 00 01 80 00 00 00 00 00 10 7E 0D 0E 00 4B 12 00 00 00 00 1A, checksum=1A, error=false
2018-02-20 22:27:52.928 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ManagementRoutingRequest [0/0 -> 0/0, cluster=0032, TID=0F, startIndex=0]
2018-02-20 22:27:52.929 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=32769, addressMode=null, radius=0, sequence=0, payload=00 00 10 7E 0D 0E 00 4B 12 00 00 00 00]
2018-02-20 22:27:52.931 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: IeeeAddressResponse [0/0 -> 0/0, cluster=8001, TID=NULL, status=SUCCESS, ieeeAddrRemoteDev=00124B000E0D7E10, nwkAddrRemoteDev=0, startIndex=null, nwkAddrAssocDevList=[]]
2018-02-20 22:27:52.932 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=50, addressMode=DEVICE, radius=31, sequence=15, payload=00 00]
2018-02-20 22:27:52.941 [DEBUG] [31.network.impl.CommandInterfaceImpl] - ->  AF_DATA_REQUEST (Packet: subsystem=null, length=12, apiId=24 01, data=FE 0C 24 01 00 00 00 00 32 00 0F 30 1F 02 00 00 39, checksum=39, error=false) 
2018-02-20 22:27:53.069 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-  AF_DATA_SRSP (FE 01 64 01 00 64)
2018-02-20 22:27:53.071 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- ZToolPacket (FE 06 45 B2 00 00 00 00 00 00 F1)
2018-02-20 22:27:53.073 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=6, apiId=45 B2, data=FE 06 45 B2 00 00 00 00 00 00 F1, checksum=F1, error=false
2018-02-20 22:27:53.076 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=32818, addressMode=null, radius=0, sequence=0, payload=00 00 00 00 00]
2018-02-20 22:27:53.079 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ManagementRoutingResponse [0/0 -> 0/0, cluster=8032, TID=NULL, status=SUCCESS, routingTableEntries=0, startIndex=0, routingTableList=[]]
2018-02-20 22:27:53.081 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- ZToolPacket (FE 0D 45 FF 00 00 00 32 80 00 00 00 00 00 00 00 00 05)
2018-02-20 22:27:53.082 [DEBUG] [com.zsmartsystems.zigbee.ZigBeeNode ] - 00124B000E0D7E10: Routing table NEW: []
2018-02-20 22:27:53.083 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=13, apiId=45 FF, data=FE 0D 45 FF 00 00 00 32 80 00 00 00 00 00 00 00 00 05, checksum=05, error=false
2018-02-20 22:27:53.084 [DEBUG] [com.zsmartsystems.zigbee.ZigBeeNode ] - 00124B000E0D7E10: Routing table OLD: []
2018-02-20 22:27:53.085 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=32818, addressMode=null, radius=0, sequence=0, payload=00 00 00 00 00]
2018-02-20 22:27:53.086 [DEBUG] [com.zsmartsystems.zigbee.ZigBeeNode ] - 00124B000E0D7E10: Routing table unchanged
2018-02-20 22:27:53.087 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ManagementRoutingResponse [0/0 -> 0/0, cluster=8032, TID=NULL, status=SUCCESS, routingTableEntries=0, startIndex=0, routingTableList=[]]
2018-02-20 22:27:53.090 [DEBUG] [com.zsmartsystems.zigbee.ZigBeeNode ] - 00124B000E0D7E10: Neighbor table unchanged
2018-02-20 22:27:53.092 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - 00124B000E0D7E10: Node 0 update
==> /var/log/openhab2/events.log <==
2018-02-20 22:27:53.111 [me.event.ThingUpdatedEvent] - Thing 'zigbee:coordinator_cc2531:652922a1' has been updated.
==> /var/log/openhab2/openhab.log <==
2018-02-20 22:27:53.150 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: done.
2018-02-20 22:27:53.152 [DEBUG] [tems.zigbee.ZigBeeNetworkMeshMonitor] - 0: Ending mesh update. Updated=true
==> /var/log/openhab2/events.log <==
2018-02-20 22:27:53.154 [me.event.ThingUpdatedEvent] - Thing 'zigbee:coordinator_cc2531:652922a1' has been updated.

rwagnon · February 21, 2018, 4:43am

Here is another log where I see the address of the Visonic device (000D6F000B111339):

2018-02-19 12:33:55.448 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - Starting ZigBee scan for zigbee:coordinator_cc2531:652922a1
2018-02-19 12:33:55.459 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - Permit join to 65532/0 for 60 seconds.
2018-02-19 12:33:55.466 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ManagementPermitJoiningRequest [0/0 -> 65532/0, cluster=0036, TID=05, permitDuration=60, tcSignificance=true]
2018-02-19 12:33:55.472 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=65532/0, profile=0000, cluster=54, addressMode=DEVICE, radius=31, sequence=5, payload=00 3C 01]
2018-02-19 12:33:55.477 [DEBUG] [31.network.impl.CommandInterfaceImpl] - ->  AF_DATA_REQUEST (Packet: subsystem=null, length=13, apiId=24 01, data=FE 0D 24 01 FC FF 00 00 36 00 05 30 1F 03 00 3C 01 09, checksum=09, error=false) 
2018-02-19 12:33:55.607 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-  AF_DATA_SRSP (FE 01 64 01 00 64)
2018-02-19 12:33:55.610 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ManagementPermitJoiningRequest [0/0 -> 0/0, cluster=0036, TID=06, permitDuration=60, tcSignificance=true]
2018-02-19 12:33:55.612 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=54, addressMode=DEVICE, radius=31, sequence=6, payload=00 3C 01]
2018-02-19 12:33:55.615 [DEBUG] [31.network.impl.CommandInterfaceImpl] - ->  AF_DATA_REQUEST (Packet: subsystem=null, length=13, apiId=24 01, data=FE 0D 24 01 00 00 00 00 36 00 06 30 1F 03 00 3C 01 09, checksum=09, error=false) 
2018-02-19 12:33:55.745 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-  AF_DATA_SRSP (FE 01 64 01 00 64)
2018-02-19 12:33:55.747 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- ZToolPacket (FE 03 45 B6 00 00 00 F0)
2018-02-19 12:33:55.747 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - ZigBee coordinator is offline - aborted scan for zigbee:coordinator_ember:0b9c5d9b
2018-02-19 12:33:55.748 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=3, apiId=45 B6, data=FE 03 45 B6 00 00 00 F0, checksum=F0, error=false
2018-02-19 12:33:55.750 [DEBUG] [e.dongle.cc2531.ZigBeeDongleTiCc2531] - Unhandled ZToolPacket type 0x45b6
2018-02-19 12:33:55.752 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- ZToolPacket (FE 0A 45 FF 00 00 00 36 80 00 00 00 00 00 06)
2018-02-19 12:33:55.753 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=10, apiId=45 FF, data=FE 0A 45 FF 00 00 00 36 80 00 00 00 00 00 06, checksum=06, error=false
2018-02-19 12:33:55.755 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=32822, addressMode=null, radius=0, sequence=0, payload=00 00]
2018-02-19 12:33:55.758 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ManagementPermitJoiningResponse [0/0 -> 0/0, cluster=8036, TID=NULL, status=SUCCESS]
2018-02-19 12:34:36.089 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- ZToolPacket (FE 0C 45 CA EE 5D 39 13 11 0B 00 6F 0D 00 00 00 62)
2018-02-19 12:34:36.093 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=12, apiId=45 CA, data=FE 0C 45 CA EE 5D 39 13 11 0B 00 6F 0D 00 00 00 62, checksum=62, error=false
2018-02-19 12:34:36.095 [DEBUG] [e.dongle.cc2531.ZigBeeDongleTiCc2531] - Unhandled ZToolPacket type 0x45ca
2018-02-19 12:34:41.072 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- ZToolPacket (FE 0C 45 CA EE 5D 39 13 11 0B 00 6F 0D 00 00 00 62)
2018-02-19 12:34:41.074 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=12, apiId=45 CA, data=FE 0C 45 CA EE 5D 39 13 11 0B 00 6F 0D 00 00 00 62, checksum=62, error=false
2018-02-19 12:34:41.076 [DEBUG] [e.dongle.cc2531.ZigBeeDongleTiCc2531] - Unhandled ZToolPacket type 0x45ca
2018-02-19 12:34:55.738 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- ZToolPacket (FE 01 45 CB 00 8F)
2018-02-19 12:34:55.740 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=1, apiId=45 CB, data=FE 01 45 CB 00 8F, checksum=8F, error=false
2018-02-19 12:34:55.743 [DEBUG] [e.dongle.cc2531.ZigBeeDongleTiCc2531] - Unhandled ZToolPacket type 0x45cb
2018-02-19 12:35:01.712 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - Starting ZigBee scan for zigbee:coordinator_cc2531:652922a1
2018-02-19 12:35:01.716 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - Permit join to 65532/0 for 60 seconds.
2018-02-19 12:35:01.720 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ManagementPermitJoiningRequest [0/0 -> 65532/0, cluster=0036, TID=07, permitDuration=60, tcSignificance=true]
2018-02-19 12:35:01.724 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=65532/0, profile=0000, cluster=54, addressMode=DEVICE, radius=31, sequence=7, payload=00 3C 01]
2018-02-19 12:35:01.729 [DEBUG] [31.network.impl.CommandInterfaceImpl] - ->  AF_DATA_REQUEST (Packet: subsystem=null, length=13, apiId=24 01, data=FE 0D 24 01 FC FF 00 00 36 00 07 30 1F 03 00 3C 01 0B, checksum=0B, error=false) 
2018-02-19 12:35:01.856 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-  AF_DATA_SRSP (FE 01 64 01 00 64)
2018-02-19 12:35:01.859 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ManagementPermitJoiningRequest [0/0 -> 0/0, cluster=0036, TID=08, permitDuration=60, tcSignificance=true]
2018-02-19 12:35:01.865 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=54, addressMode=DEVICE, radius=31, sequence=8, payload=00 3C 01]
2018-02-19 12:35:01.871 [DEBUG] [31.network.impl.CommandInterfaceImpl] - ->  AF_DATA_REQUEST (Packet: subsystem=null, length=13, apiId=24 01, data=FE 0D 24 01 00 00 00 00 36 00 08 30 1F 03 00 3C 01 07, checksum=07, error=false) 
2018-02-19 12:35:02.002 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-  AF_DATA_SRSP (FE 01 64 01 00 64)
2018-02-19 12:35:02.004 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- ZToolPacket (FE 01 45 CB 3C B3)
2018-02-19 12:35:02.005 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - ZigBee coordinator is offline - aborted scan for zigbee:coordinator_ember:0b9c5d9b
2018-02-19 12:35:02.007 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=1, apiId=45 CB, data=FE 01 45 CB 3C B3, checksum=B3, error=false
2018-02-19 12:35:02.008 [DEBUG] [e.dongle.cc2531.ZigBeeDongleTiCc2531] - Unhandled ZToolPacket type 0x45cb
2018-02-19 12:35:02.012 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- ZToolPacket (FE 03 45 B6 00 00 00 F0)
2018-02-19 12:35:02.014 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=3, apiId=45 B6, data=FE 03 45 B6 00 00 00 F0, checksum=F0, error=false
2018-02-19 12:35:02.017 [DEBUG] [e.dongle.cc2531.ZigBeeDongleTiCc2531] - Unhandled ZToolPacket type 0x45b6
2018-02-19 12:35:02.020 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- ZToolPacket (FE 0A 45 FF 00 00 00 36 80 00 00 00 00 00 06)
2018-02-19 12:35:02.023 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=10, apiId=45 FF, data=FE 0A 45 FF 00 00 00 36 80 00 00 00 00 00 06, checksum=06, error=false
2018-02-19 12:35:02.026 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=32822, addressMode=null, radius=0, sequence=0, payload=00 00]
2018-02-19 12:35:02.028 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ManagementPermitJoiningResponse [0/0 -> 0/0, cluster=8036, TID=NULL, status=SUCCESS]
2018-02-19 12:35:08.544 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- ZToolPacket (FE 0C 45 CA 0E 7B 6A 63 06 01 00 5B FD 24 00 00 7A)
2018-02-19 12:35:08.546 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=12, apiId=45 CA, data=FE 0C 45 CA 0E 7B 6A 63 06 01 00 5B FD 24 00 00 7A, checksum=7A, error=false
2018-02-19 12:35:08.549 [DEBUG] [e.dongle.cc2531.ZigBeeDongleTiCc2531] - Unhandled ZToolPacket type 0x45ca
2018-02-19 12:35:15.949 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- ZToolPacket (FE 0C 45 CA EE 5D 39 13 11 0B 00 6F 0D 00 00 00 62)
2018-02-19 12:35:15.953 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=12, apiId=45 CA, data=FE 0C 45 CA EE 5D 39 13 11 0B 00 6F 0D 00 00 00 62, checksum=62, error=false
2018-02-19 12:35:15.956 [DEBUG] [e.dongle.cc2531.ZigBeeDongleTiCc2531] - Unhandled ZToolPacket type 0x45ca
2018-02-19 12:35:15.960 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- ZToolPacket (FE 0C 45 CA EE 5D 39 13 11 0B 00 6F 0D 00 00 00 62)
2018-02-19 12:35:15.964 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=12, apiId=45 CA, data=FE 0C 45 CA EE 5D 39 13 11 0B 00 6F 0D 00 00 00 62, checksum=62, error=false
2018-02-19 12:35:15.967 [DEBUG] [e.dongle.cc2531.ZigBeeDongleTiCc2531] - Unhandled ZToolPacket type 0x45ca
2018-02-19 12:35:15.971 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- ZToolPacket (FE 0C 45 CA EE 5D 39 13 11 0B 00 6F 0D 00 00 00 62)
2018-02-19 12:35:15.974 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=12, apiId=45 CA, data=FE 0C 45 CA EE 5D 39 13 11 0B 00 6F 0D 00 00 00 62, checksum=62, error=false
2018-02-19 12:35:15.978 [DEBUG] [e.dongle.cc2531.ZigBeeDongleTiCc2531] - Unhandled ZToolPacket type 0x45ca
2018-02-19 12:35:15.982 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- ZToolPacket (FE 0C 45 CA EE 5D 39 13 11 0B 00 6F 0D 00 00 00 62)
2018-02-19 12:35:15.985 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=12, apiId=45 CA, data=FE 0C 45 CA EE 5D 39 13 11 0B 00 6F 0D 00 00 00 62, checksum=62, error=false
2018-02-19 12:35:15.988 [DEBUG] [e.dongle.cc2531.ZigBeeDongleTiCc2531] - Unhandled ZToolPacket type 0x45ca
2018-02-19 12:35:32.551 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- ZToolPacket (FE 0C 45 CA 0E 7B 6A 63 06 01 00 5B FD 24 00 00 7A)
2018-02-19 12:35:32.554 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=12, apiId=45 CA, data=FE 0C 45 CA 0E 7B 6A 63 06 01 00 5B FD 24 00 00 7A, checksum=7A, error=false
2018-02-19 12:35:32.557 [DEBUG] [e.dongle.cc2531.ZigBeeDongleTiCc2531] - Unhandled ZToolPacket type 0x45ca
2018-02-19 12:35:38.551 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- ZToolPacket (FE 0C 45 CA 0E 7B 6A 63 06 01 00 5B FD 24 00 00 7A)
2018-02-19 12:35:38.553 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=12, apiId=45 CA, data=FE 0C 45 CA 0E 7B 6A 63 06 01 00 5B FD 24 00 00 7A, checksum=7A, error=false
2018-02-19 12:35:38.556 [DEBUG] [e.dongle.cc2531.ZigBeeDongleTiCc2531] - Unhandled ZToolPacket type 0x45ca
2018-02-19 12:36:01.994 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- ZToolPacket (FE 01 45 CB 00 8F)
2018-02-19 12:36:01.996 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=1, apiId=45 CB, data=FE 01 45 CB 00 8F, checksum=8F, error=false
2018-02-19 12:36:01.999 [DEBUG] [e.dongle.cc2531.ZigBeeDongleTiCc2531] - Unhandled ZToolPacket type 0x45cb
2018-02-19 12:37:55.981 [DEBUG] [tems.zigbee.ZigBeeNetworkMeshMonitor] - Starting mesh update task with interval of 300 seconds
2018-02-19 12:38:05.998 [DEBUG] [tems.zigbee.ZigBeeNetworkMeshMonitor] - 0: Starting mesh update
2018-02-19 12:38:06.003 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ManagementLqiRequest [0/0 -> 0/0, cluster=0031, TID=09, startIndex=0]
2018-02-19 12:38:06.006 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=49, addressMode=DEVICE, radius=31, sequence=9, payload=00 00]
2018-02-19 12:38:06.009 [DEBUG] [31.network.impl.CommandInterfaceImpl] - ->  AF_DATA_REQUEST (Packet: subsystem=null, length=12, apiId=24 01, data=FE 0C 24 01 00 00 00 00 31 00 09 30 1F 02 00 00 3C, checksum=3C, error=false) 
2018-02-19 12:38:06.145 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-  AF_DATA_SRSP (FE 01 64 01 00 64)
2018-02-19 12:38:06.150 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- ZToolPacket (FE 32 45 B1 00 00 00 02 00 02 99 35 0D 9B 16 53 20 35 39 13 11 0B 00 6F 0D 00 EE 5D 12 02 01 AA 99 35 0D 9B 16 53 20 35 6A 63 06 01 00 5B FD 24 0E 7B 12 02 01 AA DE)
2018-02-19 12:38:06.153 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=50, apiId=45 B1, data=FE 32 45 B1 00 00 00 02 00 02 99 35 0D 9B 16 53 20 35 39 13 11 0B 00 6F 0D 00 EE 5D 12 02 01 AA 99 35 0D 9B 16 53 20 35 6A 63 06 01 00 5B FD 24 0E 7B 12 02 01 AA DE, checksum=DE, error=false
2018-02-19 12:38:06.167 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=32817, addressMode=null, radius=0, sequence=0, payload=00 00 02 00 02 99 35 0D 9B 16 53 20 35 39 13 11 0B 00 6F 0D 00 EE 5D 12 02 01 AA 99 35 0D 9B 16 53 20 35 6A 63 06 01 00 5B FD 24 0E 7B 12 02 01 AA]
2018-02-19 12:38:06.186 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ManagementLqiResponse [0/0 -> 0/0, cluster=8031, TID=NULL, status=SUCCESS, neighborTableEntries=2, startIndex=0, neighborTableList=[NeighborTable [extendedPanId=352053169B0D3599, extendedAddress=000D6F000B111339, networkAddress=24046, deviceType=END_DEVICE, rxOnWhenIdle=RX_OFF, relationship=CHILD, permitJoining=UNKNOWN, depth=1, lqi=170], NeighborTable [extendedPanId=352053169B0D3599, extendedAddress=24FD5B000106636A, networkAddress=31502, deviceType=END_DEVICE, rxOnWhenIdle=RX_OFF, relationship=CHILD, permitJoining=UNKNOWN, depth=1, lqi=170]]]
2018-02-19 12:38:06.197 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- ZToolPacket (FE 39 45 FF 00 00 00 31 80 00 00 00 00 00 02 00 02 99 35 0D 9B 16 53 20 35 39 13 11 0B 00 6F 0D 00 EE 5D 12 02 01 AA 99 35 0D 9B 16 53 20 35 6A 63 06 01 00 5B FD 24 0E 7B 12 02 01 AA 2A)
2018-02-19 12:38:06.208 [DEBUG] [tems.zigbee.ZigBeeNetworkMeshMonitor] - 24046: Starting mesh update
2018-02-19 12:38:06.211 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=57, apiId=45 FF, data=FE 39 45 FF 00 00 00 31 80 00 00 00 00 00 02 00 02 99 35 0D 9B 16 53 20 35 39 13 11 0B 00 6F 0D 00 EE 5D 12 02 01 AA 99 35 0D 9B 16 53 20 35 6A 63 06 01 00 5B FD 24 0E 7B 12 02 01 AA 2A, checksum=2A, error=false
2018-02-19 12:38:06.213 [DEBUG] [tems.zigbee.ZigBeeNetworkMeshMonitor] - 24046: ZigBee node not found during mesh update
2018-02-19 12:38:06.219 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: IeeeAddressRequest [0/0 -> 0/0, cluster=0001, TID=0A, nwkAddrOfInterest=0, requestType=1, startIndex=0]
2018-02-19 12:38:06.219 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=32817, addressMode=null, radius=0, sequence=0, payload=00 00 02 00 02 99 35 0D 9B 16 53 20 35 39 13 11 0B 00 6F 0D 00 EE 5D 12 02 01 AA 99 35 0D 9B 16 53 20 35 6A 63 06 01 00 5B FD 24 0E 7B 12 02 01 AA]
2018-02-19 12:38:06.222 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=1, addressMode=DEVICE, radius=31, sequence=10, payload=00 00 00 01 00]
2018-02-19 12:38:06.225 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ManagementLqiResponse [0/0 -> 0/0, cluster=8031, TID=NULL, status=SUCCESS, neighborTableEntries=2, startIndex=0, neighborTableList=[NeighborTable [extendedPanId=352053169B0D3599, extendedAddress=000D6F000B111339, networkAddress=24046, deviceType=END_DEVICE, rxOnWhenIdle=RX_OFF, relationship=CHILD, permitJoining=UNKNOWN, depth=1, lqi=170], NeighborTable [extendedPanId=352053169B0D3599, extendedAddress=24FD5B000106636A, networkAddress=31502, deviceType=END_DEVICE, rxOnWhenIdle=RX_OFF, relationship=CHILD, permitJoining=UNKNOWN, depth=1, lqi=170]]]
2018-02-19 12:38:06.249 [DEBUG] [bee.internal.ZigBeeNetworkDiscoverer] - 24046: Scheduling node discovery
2018-02-19 12:38:06.224 [DEBUG] [31.network.impl.CommandInterfaceImpl] - ->  AF_DATA_REQUEST (Packet: subsystem=null, length=15, apiId=24 01, data=FE 0F 24 01 00 00 00 00 01 00 0A 30 1F 05 00 00 00 01 00 0A, checksum=0A, error=false) 
2018-02-19 12:38:06.252 [DEBUG] [tems.zigbee.ZigBeeNetworkMeshMonitor] - 31502: Starting mesh update
2018-02-19 12:38:06.256 [DEBUG] [tems.zigbee.ZigBeeNetworkMeshMonitor] - 31502: ZigBee node not found during mesh update
2018-02-19 12:38:06.256 [DEBUG] [bee.internal.ZigBeeNetworkDiscoverer] - 24046: Starting node discovery
2018-02-19 12:38:06.265 [DEBUG] [bee.internal.ZigBeeNetworkDiscoverer] - 31502: Scheduling node discovery
2018-02-19 12:38:06.268 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: IeeeAddressRequest [0/0 -> 24046/0, cluster=0001, TID=0B, nwkAddrOfInterest=24046, requestType=1, startIndex=0]
2018-02-19 12:38:06.270 [DEBUG] [bee.internal.ZigBeeNetworkDiscoverer] - 31502: Starting node discovery
2018-02-19 12:38:06.272 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: IeeeAddressRequest [0/0 -> 31502/0, cluster=0001, TID=0C, nwkAddrOfInterest=31502, requestType=1, startIndex=0]
2018-02-19 12:38:06.275 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=31502/0, profile=0000, cluster=1, addressMode=DEVICE, radius=31, sequence=12, payload=00 0E 7B 01 00]
2018-02-19 12:38:06.278 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=24046/0, profile=0000, cluster=1, addressMode=DEVICE, radius=31, sequence=11, payload=00 EE 5D 01 00]
2018-02-19 12:38:06.384 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-  AF_DATA_SRSP (FE 01 64 01 00 64)
2018-02-19 12:38:06.386 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- ZToolPacket (FE 11 45 81 00 10 7E 0D 0E 00 4B 12 00 00 00 00 02 EE 5D 0E 7B 25)
2018-02-19 12:38:06.387 [DEBUG] [31.network.impl.CommandInterfaceImpl] - ->  AF_DATA_REQUEST (Packet: subsystem=null, length=15, apiId=24 01, data=FE 0F 24 01 EE 5D 00 00 01 00 0B 30 1F 05 00 EE 5D 01 00 0B, checksum=0B, error=false) 
2018-02-19 12:38:06.388 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=17, apiId=45 81, data=FE 11 45 81 00 10 7E 0D 0E 00 4B 12 00 00 00 00 02 EE 5D 0E 7B 25, checksum=25, error=false
2018-02-19 12:38:06.390 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=32769, addressMode=null, radius=0, sequence=0, payload=00 00 10 7E 0D 0E 00 4B 12 00 00 00 02 00 EE 5D 0E 7B]
2018-02-19 12:38:06.393 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: IeeeAddressResponse [0/0 -> 0/0, cluster=8001, TID=NULL, status=SUCCESS, ieeeAddrRemoteDev=00124B000E0D7E10, nwkAddrRemoteDev=0, startIndex=0, nwkAddrAssocDevList=[24046, 31502]]
2018-02-19 12:38:06.396 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- ZToolPacket (FE 1A 45 FF 00 00 00 01 80 00 00 00 00 00 10 7E 0D 0E 00 4B 12 00 00 00 02 00 EE 5D 0E 7B D1)
2018-02-19 12:38:06.396 [DEBUG] [com.zsmartsystems.zigbee.ZigBeeNode ] - 00124B000E0D7E10: Associated devices table updated: [24046, 31502]
2018-02-19 12:38:06.399 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=26, apiId=45 FF, data=FE 1A 45 FF 00 00 00 01 80 00 00 00 00 00 10 7E 0D 0E 00 4B 12 00 00 00 02 00 EE 5D 0E 7B D1, checksum=D1, error=false
2018-02-19 12:38:06.401 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ManagementRoutingRequest [0/0 -> 0/0, cluster=0032, TID=0D, startIndex=0]
2018-02-19 12:38:06.402 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=32769, addressMode=null, radius=0, sequence=0, payload=00 00 10 7E 0D 0E 00 4B 12 00 00 00 02 00 EE 5D 0E 7B]
2018-02-19 12:38:06.405 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=50, addressMode=DEVICE, radius=31, sequence=13, payload=00 00]
2018-02-19 12:38:06.407 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: IeeeAddressResponse [0/0 -> 0/0, cluster=8001, TID=NULL, status=SUCCESS, ieeeAddrRemoteDev=00124B000E0D7E10, nwkAddrRemoteDev=0, startIndex=0, nwkAddrAssocDevList=[24046, 31502]]
2018-02-19 12:38:06.508 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-  AF_DATA_SRSP (FE 01 64 01 00 64)
2018-02-19 12:38:06.509 [DEBUG] [31.network.impl.CommandInterfaceImpl] - ->  AF_DATA_REQUEST (Packet: subsystem=null, length=15, apiId=24 01, data=FE 0F 24 01 0E 7B 00 00 01 00 0C 30 1F 05 00 0E 7B 01 00 0C, checksum=0C, error=false) 
2018-02-19 12:38:06.635 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-  AF_DATA_SRSP (FE 01 64 01 00 64)
2018-02-19 12:38:06.637 [DEBUG] [31.network.impl.CommandInterfaceImpl] - ->  AF_DATA_REQUEST (Packet: subsystem=null, length=12, apiId=24 01, data=FE 0C 24 01 00 00 00 00 32 00 0D 30 1F 02 00 00 3B, checksum=3B, error=false) 
2018-02-19 12:38:06.766 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-  AF_DATA_SRSP (FE 01 64 01 00 64)
2018-02-19 12:38:06.768 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- ZToolPacket (FE 06 45 B2 00 00 00 00 00 00 F1)
2018-02-19 12:38:06.771 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=6, apiId=45 B2, data=FE 06 45 B2 00 00 00 00 00 00 F1, checksum=F1, error=false
2018-02-19 12:38:06.774 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=32818, addressMode=null, radius=0, sequence=0, payload=00 00 00 00 00]
2018-02-19 12:38:06.778 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ManagementRoutingResponse [0/0 -> 0/0, cluster=8032, TID=NULL, status=SUCCESS, routingTableEntries=0, startIndex=0, routingTableList=[]]
2018-02-19 12:38:06.780 [DEBUG] [com.zsmartsystems.zigbee.ZigBeeNode ] - 00124B000E0D7E10: Routing table NEW: []
2018-02-19 12:38:06.780 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- ZToolPacket (FE 0D 45 FF 00 00 00 32 80 00 00 00 00 00 00 00 00 05)
2018-02-19 12:38:06.782 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=13, apiId=45 FF, data=FE 0D 45 FF 00 00 00 32 80 00 00 00 00 00 00 00 00 05, checksum=05, error=false
2018-02-19 12:38:06.783 [DEBUG] [com.zsmartsystems.zigbee.ZigBeeNode ] - 00124B000E0D7E10: Routing table OLD: []
2018-02-19 12:38:06.785 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=32818, addressMode=null, radius=0, sequence=0, payload=00 00 00 00 00]
2018-02-19 12:38:06.786 [DEBUG] [com.zsmartsystems.zigbee.ZigBeeNode ] - 00124B000E0D7E10: Routing table unchanged
2018-02-19 12:38:06.789 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ManagementRoutingResponse [0/0 -> 0/0, cluster=8032, TID=NULL, status=SUCCESS, routingTableEntries=0, startIndex=0, routingTableList=[]]
2018-02-19 12:38:06.791 [DEBUG] [com.zsmartsystems.zigbee.ZigBeeNode ] - 00124B000E0D7E10: Neighbor table updated: [NeighborTable [extendedPanId=352053169B0D3599, extendedAddress=000D6F000B111339, networkAddress=24046, deviceType=END_DEVICE, rxOnWhenIdle=RX_OFF, relationship=CHILD, permitJoining=UNKNOWN, depth=1, lqi=170], NeighborTable [extendedPanId=352053169B0D3599, extendedAddress=24FD5B000106636A, networkAddress=31502, deviceType=END_DEVICE, rxOnWhenIdle=RX_OFF, relationship=CHILD, permitJoining=UNKNOWN, depth=1, lqi=170]]
2018-02-19 12:38:06.794 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - 00124B000E0D7E10: Node 0 update
==> /var/log/openhab2/events.log <==
2018-02-19 12:38:06.813 [me.event.ThingUpdatedEvent] - Thing 'zigbee:coordinator_cc2531:652922a1' has been updated.
==> /var/log/openhab2/openhab.log <==
2018-02-19 12:38:06.832 [DEBUG] [nal.ZigBeeNetworkStateSerializerImpl] - Saving ZigBee network state: done.
2018-02-19 12:38:06.838 [DEBUG] [tems.zigbee.ZigBeeNetworkMeshMonitor] - 0: Ending mesh update. Updated=true
==> /var/log/openhab2/events.log <==
2018-02-19 12:38:06.845 [me.event.ThingUpdatedEvent] - Thing 'zigbee:coordinator_cc2531:652922a1' has been updated.

boon · May 13, 2018, 11:37am

Did you solve it ?
Which device you bought?

boon · May 13, 2018, 12:14pm

Can i buy this dongle for max compatibility:

Or ist this a better deal?

I want to switch osram or philipps hue lights?

chris · May 14, 2018, 7:19am

I would say that at the moment, support is better for the Telegesis dongle than the TI dongle. TI tends to be a bit more difficult to get working as you need to program it, and getting the right firmware etc can be a pain (but it is cheaper!).

Elelabs · May 16, 2018, 6:27am

Hello guys,

We have created 2 new products, compatible with OpenHAB ZigBee bindings:

Elelabs ZigBee USB Adapter
Elelabs ZigBee Raspberry Pi Shield

EZBS_pic_3.jpg1000×1000 199 KB

Both of them are compatible and tested with latest OpenHAB and latest Z-Wave Binding.
We have also prepared User Guides on how to set them up.
Hope you find them useful.