I’v been struggling to get the Zigbee binding working, so thought I would go back to square 1. I have removed my old testing 2.2 binding and have the 2.3 binding installed via paperUI / Habmin. In Karaf it shows the 2.3 and only the 2.3 binding as active.
I have the HUSBZB-1 dual zwave/zigbee stick. Zwave is USB0 and working well. Zigbee is setup as the Ember EM35x on port ttyUSB1 with baud rate of 57600 and shows online in both PaperUI and Habmin.
But when I try to start a discovery, Habmin says “Discovery Failed for Zigbee Binding”
I’ve enabled debug logging and this is what I see when I try to start the discovery
2018-03-20 18:59:10.620 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=3, notRdy=false]
2018-03-20 18:59:19.574 [DEBUG] [internal.ZigBeeNodeServiceDiscoverer] - 50137: Node SVC Discovery NetworkAddressRequest returned null
2018-03-20 18:59:19.574 [DEBUG] [internal.ZigBeeNodeServiceDiscoverer] - 7CB03EAA00B0013D: Node SVC Discovery request NWK_ADDRESS failed. Retry 10, wait 8956ms before retry.
2018-03-20 18:59:28.531 [DEBUG] [internal.ZigBeeNodeServiceDiscoverer] - 7CB03EAA00B0013D: Node SVC Discovery running
2018-03-20 18:59:28.531 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: NetworkAddressRequest [0/0 -> 65533/0, cluster=0000, TID=0D, ieeeAddr=7CB03EAA00B0013D, requestType=0, startIndex=0]
2018-03-20 18:59:28.532 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=65533/0, profile=0000, cluster=0, addressMode=DEVICE, radius=31, sequence=13, payload=00 3D 01 B0 00 AA 3E B0 7C 00 00]
2018-03-20 18:59:28.532 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - EzspSendBroadcastRequest [destination=65533, apsFrame=EmberApsFrame [profileId=0, clusterId=0, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY, EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY], groupId=0, sequence=13], radius=31, messageTag=13, messageContents=00 3D 01 B0 00 AA 3E B0 7C 00 00]
2018-03-20 18:59:28.532 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1
2018-03-20 18:59:28.532 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspSendBroadcastRequest [destination=65533, apsFrame=EmberApsFrame [profileId=0, clusterId=0, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY, EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY], groupId=0, sequence=13], radius=31, messageTag=13, messageContents=00 3D 01 B0 00 AA 3E B0 7C 00 00]
2018-03-20 18:59:28.533 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=4, ackNum=3, reTx=false, data=0D 00 36 FD FF 00 00 00 00 00 00 40 11 00 00 0D 1F 0D 0B 00 3D 01 B0 00 AA 3E B0 7C 00 00]
2018-03-20 18:59:28.652 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=3, ackNum=5, reTx=false, data=0D 80 36 00 6A]
2018-03-20 18:59:28.653 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=4, ackNum=3, reTx=false, data=0D 00 36 FD FF 00 00 00 00 00 00 40 11 00 00 0D 1F 0D 0B 00 3D 01 B0 00 AA 3E B0 7C 00 00]
2018-03-20 18:59:28.653 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspSendBroadcastResponse [status=EMBER_SUCCESS, sequence=106]
2018-03-20 18:59:28.653 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX: EzspSendBroadcastResponse [status=EMBER_SUCCESS, sequence=106]
2018-03-20 18:59:28.654 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Unhandled EZSP Frame: EzspSendBroadcastResponse [status=EMBER_SUCCESS, sequence=106]
2018-03-20 18:59:28.654 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=4, notRdy=false]
2018-03-20 18:59:28.654 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=4, ackNum=5, reTx=false, data=0D 90 45 05 00 00 00 00 00 00 08 01 00 00 6A FF 00 00 00 FF FF 0B 00 3D 01 B0 00 AA 3E B0 7C 00 00]
2018-03-20 18:59:28.657 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspIncomingMessageHandler [type=EMBER_INCOMING_BROADCAST_LOOPBACK, apsFrame=EmberApsFrame [profileId=0, clusterId=0, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=106], lastHopLqi=255, lastHopRssi=0, sender=0, bindingIndex=255, addressIndex=255, messageContents=00 3D 01 B0 00 AA 3E B0 7C 00 00]
2018-03-20 18:59:28.657 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX: EzspIncomingMessageHandler [type=EMBER_INCOMING_BROADCAST_LOOPBACK, apsFrame=EmberApsFrame [profileId=0, clusterId=0, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=106], lastHopLqi=255, lastHopRssi=0, sender=0, bindingIndex=255, addressIndex=255, messageContents=00 3D 01 B0 00 AA 3E B0 7C 00 00]
2018-03-20 18:59:28.660 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=0, addressMode=null, radius=0, sequence=13, payload=00 3D 01 B0 00 AA 3E B0 7C 00 00]
2018-03-20 18:59:28.661 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: NetworkAddressRequest [0/0 -> 0/0, cluster=0000, TID=NULL, ieeeAddr=7CB03EAA00B0013D, requestType=0, startIndex=0]
2018-03-20 18:59:28.661 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=5, notRdy=false]
2018-03-20 18:59:29.621 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=5, ackNum=5, reTx=false, data=0D 90 3F 04 FD FF 00 00 00 00 00 00 08 01 00 00 6A 0D 00 00]
2018-03-20 18:59:29.623 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspMessageSentHandler [type=EMBER_OUTGOING_MULTICAST_WITH_ALIAS, indexOrDestination=65533, apsFrame=EmberApsFrame [profileId=0, clusterId=0, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=106], messageTag=13, status=EMBER_SUCCESS, messageContents=]
2018-03-20 18:59:29.624 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX: EzspMessageSentHandler [type=EMBER_OUTGOING_MULTICAST_WITH_ALIAS, indexOrDestination=65533, apsFrame=EmberApsFrame [profileId=0, clusterId=0, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=106], messageTag=13, status=EMBER_SUCCESS, messageContents=]
2018-03-20 18:59:29.624 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Unhandled EZSP Frame: EzspMessageSentHandler [type=EMBER_OUTGOING_MULTICAST_WITH_ALIAS, indexOrDestination=65533, apsFrame=EmberApsFrame [profileId=0, clusterId=0, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=106], messageTag=13, status=EMBER_SUCCESS, messageContents=]
2018-03-20 18:59:29.624 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=6, notRdy=false]
2018-03-20 18:59:38.533 [DEBUG] [internal.ZigBeeNodeServiceDiscoverer] - 50137: Node SVC Discovery NetworkAddressRequest returned null
2018-03-20 18:59:38.534 [DEBUG] [internal.ZigBeeNodeServiceDiscoverer] - 7CB03EAA00B0013D: Node SVC Discovery request NWK_ADDRESS failed. Retry 11, wait 15673ms before retry.
2018-03-20 18:59:54.208 [DEBUG] [internal.ZigBeeNodeServiceDiscoverer] - 7CB03EAA00B0013D: Node SVC Discovery running
2018-03-20 18:59:54.208 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: NetworkAddressRequest [0/0 -> 65533/0, cluster=0000, TID=0E, ieeeAddr=7CB03EAA00B0013D, requestType=0, startIndex=0]
2018-03-20 18:59:54.209 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=65533/0, profile=0000, cluster=0, addressMode=DEVICE, radius=31, sequence=14, payload=00 3D 01 B0 00 AA 3E B0 7C 00 00]
2018-03-20 18:59:54.209 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - EzspSendBroadcastRequest [destination=65533, apsFrame=EmberApsFrame [profileId=0, clusterId=0, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY, EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY], groupId=0, sequence=14], radius=31, messageTag=14, messageContents=00 3D 01 B0 00 AA 3E B0 7C 00 00]
2018-03-20 18:59:54.212 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: TX EZSP queue: 1
2018-03-20 18:59:54.213 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - TX EZSP: EzspSendBroadcastRequest [destination=65533, apsFrame=EmberApsFrame [profileId=0, clusterId=0, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY, EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY], groupId=0, sequence=14], radius=31, messageTag=14, messageContents=00 3D 01 B0 00 AA 3E B0 7C 00 00]
2018-03-20 18:59:54.213 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=5, ackNum=6, reTx=false, data=0E 00 36 FD FF 00 00 00 00 00 00 40 11 00 00 0E 1F 0E 0B 00 3D 01 B0 00 AA 3E B0 7C 00 00]
2018-03-20 18:59:54.332 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=6, ackNum=6, reTx=false, data=0E 80 36 00 6B]
2018-03-20 18:59:54.332 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - ASH: Frame acked and removed AshFrameData [frmNum=5, ackNum=6, reTx=false, data=0E 00 36 FD FF 00 00 00 00 00 00 40 11 00 00 0E 1F 0E 0B 00 3D 01 B0 00 AA 3E B0 7C 00 00]
2018-03-20 18:59:54.333 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspSendBroadcastResponse [status=EMBER_SUCCESS, sequence=107]
2018-03-20 18:59:54.333 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX: EzspSendBroadcastResponse [status=EMBER_SUCCESS, sequence=107]
2018-03-20 18:59:54.334 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Unhandled EZSP Frame: EzspSendBroadcastResponse [status=EMBER_SUCCESS, sequence=107]
2018-03-20 18:59:54.334 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=7, notRdy=false]
2018-03-20 18:59:54.334 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=7, ackNum=6, reTx=false, data=0E 90 45 05 00 00 00 00 00 00 08 01 00 00 6B FF 00 00 00 FF FF 0B 00 3D 01 B0 00 AA 3E B0 7C 00 00]
2018-03-20 18:59:54.335 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspIncomingMessageHandler [type=EMBER_INCOMING_BROADCAST_LOOPBACK, apsFrame=EmberApsFrame [profileId=0, clusterId=0, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=107], lastHopLqi=255, lastHopRssi=0, sender=0, bindingIndex=255, addressIndex=255, messageContents=00 3D 01 B0 00 AA 3E B0 7C 00 00]
2018-03-20 18:59:54.335 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX: EzspIncomingMessageHandler [type=EMBER_INCOMING_BROADCAST_LOOPBACK, apsFrame=EmberApsFrame [profileId=0, clusterId=0, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=107], lastHopLqi=255, lastHopRssi=0, sender=0, bindingIndex=255, addressIndex=255, messageContents=00 3D 01 B0 00 AA 3E B0 7C 00 00]
2018-03-20 18:59:54.335 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=0, addressMode=null, radius=0, sequence=14, payload=00 3D 01 B0 00 AA 3E B0 7C 00 00]
2018-03-20 18:59:54.336 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: NetworkAddressRequest [0/0 -> 0/0, cluster=0000, TID=NULL, ieeeAddr=7CB03EAA00B0013D, requestType=0, startIndex=0]
2018-03-20 18:59:54.336 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=0, notRdy=false]
2018-03-20 18:59:55.314 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=0, ackNum=6, reTx=false, data=0E 90 3F 04 FD FF 00 00 00 00 00 00 08 01 00 00 6B 0E 00 00]
2018-03-20 18:59:55.317 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - RX EZSP: EzspMessageSentHandler [type=EMBER_OUTGOING_MULTICAST_WITH_ALIAS, indexOrDestination=65533, apsFrame=EmberApsFrame [profileId=0, clusterId=0, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=107], messageTag=14, status=EMBER_SUCCESS, messageContents=]
2018-03-20 18:59:55.318 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX: EzspMessageSentHandler [type=EMBER_OUTGOING_MULTICAST_WITH_ALIAS, indexOrDestination=65533, apsFrame=EmberApsFrame [profileId=0, clusterId=0, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=107], messageTag=14, status=EMBER_SUCCESS, messageContents=]
2018-03-20 18:59:55.318 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Unhandled EZSP Frame: EzspMessageSentHandler [type=EMBER_OUTGOING_MULTICAST_WITH_ALIAS, indexOrDestination=65533, apsFrame=EmberApsFrame [profileId=0, clusterId=0, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=107], messageTag=14, status=EMBER_SUCCESS, messageContents=]
2018-03-20 18:59:55.319 [DEBUG] [e.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=1, notRdy=false]