Zigbee binding

Mike_Bagdanoff · October 19, 2017, 6:57pm

I’ve got this working with a linear husbzb-1 and 3 SmartThings outlets. Thanks for getting this going @chris. Do you have an idea when motion and door sensors will be supported? I have a bag of ST sensors waiting to be put to good use

chris · October 19, 2017, 8:21pm

This will be added in the coming weeks. I have a few other features that need to be added first, but it is definitely a requirement in the November timeframe. (and I have the ST motion sensor so can also test this one).

Mike_Bagdanoff · October 20, 2017, 7:08pm

@chris -

yesterday I noticed my logs were blowing up and after some poking around, I noticed that one of my zigbee devices was offline. I restarted the binding and the device stayed offline with the status zigbee.status.offline_nodenotfound. I checked the logs and found a crapload of errors.

I have a Linear HUSBZB-1 and 3 SmartThings Outlets in my zigbee mesh.

2017-10-19 21:06:37.425 [DEBUG] [ee.internal.ZigBeeNetworkMeshMonitor] - 0: Starting mesh update
2017-10-19 21:06:37.429 [DEBUG] [ee.internal.ZigBeeNetworkMeshMonitor] - 0: ZigBee node not found during mesh update
2017-10-19 21:06:37.435 [DEBUG] [bee.internal.ZigBeeNetworkDiscoverer] - 0: Scheduling node discovery
2017-10-19 21:06:37.443 [DEBUG] [bee.internal.ZigBeeNetworkDiscoverer] - 0: Starting node discovery
2017-10-19 21:06:37.448 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: IeeeAddressRequest [0/0 -> 0/0, cluster=0001, TID=04, nwkAddrOfInterest=0, requestType=1, startIndex=0]
2017-10-19 21:06:37.457 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - EzspSendUnicastRequest [type=EMBER_OUTGOING_DIRECT, indexOrDestination=0, apsFrame=EmberApsFrame [profileId=0, clusterId=1, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=4], messageTag=4, messageContents=00 00 00 01 00]
2017-10-19 21:06:37.475 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - TX EZSP queue: 1
2017-10-19 21:06:37.481 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - TX EZSP: EzspSendUnicastRequest [type=EMBER_OUTGOING_DIRECT, indexOrDestination=0, apsFrame=EmberApsFrame [profileId=0, clusterId=1, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=4], messageTag=4, messageContents=00 00 00 01 00]
2017-10-19 21:06:37.510 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=1, ackNum=3, reTx=false, data=04 00 34 00 00 00 00 00 01 00 00 00 40 11 00 00 04 04 05 00 00 00 01 00]
2017-10-19 21:06:37.930 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=1, ackNum=3, reTx=true, data=04 00 34 00 00 00 00 00 01 00 00 00 40 11 00 00 04 04 05 00 00 00 01 00]
2017-10-19 21:06:38.339 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=1, ackNum=3, reTx=true, data=04 00 34 00 00 00 00 00 01 00 00 00 40 11 00 00 04 04 05 00 00 00 01 00]
2017-10-19 21:06:38.750 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=1, ackNum=3, reTx=true, data=04 00 34 00 00 00 00 00 01 00 00 00 40 11 00 00 04 04 05 00 00 00 01 00]
2017-10-19 21:06:39.162 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=1, ackNum=3, reTx=true, data=04 00 34 00 00 00 00 00 01 00 00 00 40 11 00 00 04 04 05 00 00 00 01 00]
2017-10-19 21:06:39.571 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=1, ackNum=3, reTx=true, data=04 00 34 00 00 00 00 00 01 00 00 00 40 11 00 00 04 04 05 00 00 00 01 00]
2017-10-19 21:06:39.981 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Error: number of retries exceeded [6].
2017-10-19 21:06:39.987 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=1, ackNum=3, reTx=true, data=04 00 34 00 00 00 00 00 01 00 00 00 40 11 00 00 04 04 05 00 00 00 01 00]
2017-10-19 21:06:40.398 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Error: number of retries exceeded [7].
2017-10-19 21:06:40.403 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=1, ackNum=3, reTx=true, data=04 00 34 00 00 00 00 00 01 00 00 00 40 11 00 00 04 04 05 00 00 00 01 00]
2017-10-19 21:06:40.812 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Error: number of retries exceeded [8].
2017-10-19 21:06:40.817 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=1, ackNum=3, reTx=true, data=04 00 34 00 00 00 00 00 01 00 00 00 40 11 00 00 04 04 05 00 00 00 01 00]
2017-10-19 21:06:41.226 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Error: number of retries exceeded [9].
2017-10-19 21:06:41.231 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=1, ackNum=3, reTx=true, data=04 00 34 00 00 00 00 00 01 00 00 00 40 11 00 00 04 04 05 00 00 00 01 00]
2017-10-19 21:06:41.644 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Error: number of retries exceeded [10].
2017-10-19 21:06:41.649 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=1, ackNum=3, reTx=true, data=04 00 34 00 00 00 00 00 01 00 00 00 40 11 00 00 04 04 05 00 00 00 01 00]
2017-10-19 21:06:42.058 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Error: number of retries exceeded [11].
2017-10-19 21:06:42.070 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=1, ackNum=3, reTx=true, data=04 00 34 00 00 00 00 00 01 00 00 00 40 11 00 00 04 04 05 00 00 00 01 00]
2017-10-19 21:06:42.480 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Error: number of retries exceeded [12].
2017-10-19 21:06:42.490 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=1, ackNum=3, reTx=true, data=04 00 34 00 00 00 00 00 01 00 00 00 40 11 00 00 04 04 05 00 00 00 01 00]
2017-10-19 21:06:42.900 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Error: number of retries exceeded [13].
2017-10-19 21:06:42.905 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=1, ackNum=3, reTx=true, data=04 00 34 00 00 00 00 00 01 00 00 00 40 11 00 00 04 04 05 00 00 00 01 00]
2017-10-19 21:06:43.314 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Error: number of retries exceeded [14].
2017-10-19 21:06:43.327 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=1, ackNum=3, reTx=true, data=04 00 34 00 00 00 00 00 01 00 00 00 40 11 00 00 04 04 05 00 00 00 01 00]
2017-10-19 21:06:43.750 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Error: number of retries exceeded [15].
2017-10-19 21:06:43.755 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=1, ackNum=3, reTx=true, data=04 00 34 00 00 00 00 00 01 00 00 00 40 11 00 00 04 04 05 00 00 00 01 00]
2017-10-19 21:06:44.164 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Error: number of retries exceeded [16].
2017-10-19 21:06:44.190 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=1, ackNum=3, reTx=true, data=04 00 34 00 00 00 00 00 01 00 00 00 40 11 00 00 04 04 05 00 00 00 01 00]
2017-10-19 21:06:44.605 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Error: number of retries exceeded [17].
2017-10-19 21:06:44.610 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=1, ackNum=3, reTx=true, data=04 00 34 00 00 00 00 00 01 00 00 00 40 11 00 00 04 04 05 00 00 00 01 00]
2017-10-19 21:06:45.019 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Error: number of retries exceeded [18].
2017-10-19 21:06:45.031 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=1, ackNum=3, reTx=true, data=04 00 34 00 00 00 00 00 01 00 00 00 40 11 00 00 04 04 05 00 00 00 01 00]
2017-10-19 21:06:45.446 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Error: number of retries exceeded [19].
2017-10-19 21:06:45.451 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=1, ackNum=3, reTx=true, data=04 00 34 00 00 00 00 00 01 00 00 00 40 11 00 00 04 04 05 00 00 00 01 00]
2017-10-19 21:06:45.865 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Error: number of retries exceeded [20].
2017-10-19 21:06:45.870 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=1, ackNum=3, reTx=true, data=04 00 34 00 00 00 00 00 01 00 00 00 40 11 00 00 04 04 05 00 00 00 01 00]
2017-10-19 21:06:46.280 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Error: number of retries exceeded [21].
2017-10-19 21:06:46.285 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=1, ackNum=3, reTx=true, data=04 00 34 00 00 00 00 00 01 00 00 00 40 11 00 00 04 04 05 00 00 00 01 00]
2017-10-19 21:06:46.695 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Error: number of retries exceeded [22].
2017-10-19 21:06:46.706 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=1, ackNum=3, reTx=true, data=04 00 34 00 00 00 00 00 01 00 00 00 40 11 00 00 04 04 05 00 00 00 01 00]
2017-10-19 21:06:47.123 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Error: number of retries exceeded [23].
2017-10-19 21:06:47.128 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=1, ackNum=3, reTx=true, data=04 00 34 00 00 00 00 00 01 00 00 00 40 11 00 00 04 04 05 00 00 00 01 00]
2017-10-19 21:06:47.530 [DEBUG] [bee.internal.ZigBeeNetworkDiscoverer] - Ieee Address for 0 returned null
2017-10-19 21:06:47.538 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Error: number of retries exceeded [24].
2017-10-19 21:06:47.544 [DEBUG] [bee.internal.ZigBeeNetworkDiscoverer] - 0: Discovery request IEEE_ADDRESS failed. Wait before retry.
2017-10-19 21:06:47.556 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=1, ackNum=3, reTx=true, data=04 00 34 00 00 00 00 00 01 00 00 00 40 11 00 00 04 04 05 00 00 00 01 00]
2017-10-19 21:06:47.972 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Error: number of retries exceeded [25].
2017-10-19 21:06:47.983 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=1, ackNum=3, reTx=true, data=04 00 34 00 00 00 00 00 01 00 00 00 40 11 00 00 04 04 05 00 00 00 01 00]
2017-10-19 21:06:48.394 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Error: number of retries exceeded [26].
2017-10-19 21:06:48.403 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=1, ackNum=3, reTx=true, data=04 00 34 00 00 00 00 00 01 00 00 00 40 11 00 00 04 04 05 00 00 00 01 00]
2017-10-19 21:06:48.814 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Error: number of retries exceeded [27].
2017-10-19 21:06:48.819 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=1, ackNum=3, reTx=true, data=04 00 34 00 00 00 00 00 01 00 00 00 40 11 00 00 04 04 05 00 00 00 01 00]
2017-10-19 21:06:49.063 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: IeeeAddressRequest [0/0 -> 0/0, cluster=0001, TID=05, nwkAddrOfInterest=0, requestType=1, startIndex=0]
2017-10-19 21:06:49.083 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - EzspSendUnicastRequest [type=EMBER_OUTGOING_DIRECT, indexOrDestination=0, apsFrame=EmberApsFrame [profileId=0, clusterId=1, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=5], messageTag=5, messageContents=00 00 00 01 00]
2017-10-19 21:06:49.114 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - TX EZSP queue: 1
2017-10-19 21:06:49.118 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2017-10-19 21:06:49.229 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Error: number of retries exceeded [28].
2017-10-19 21:06:49.239 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=1, ackNum=3, reTx=true, data=04 00 34 00 00 00 00 00 01 00 00 00 40 11 00 00 04 04 05 00 00 00 01 00]
2017-10-19 21:06:49.650 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Error: number of retries exceeded [29].
2017-10-19 21:06:49.658 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=1, ackNum=3, reTx=true, data=04 00 34 00 00 00 00 00 01 00 00 00 40 11 00 00 04 04 05 00 00 00 01 00]
2017-10-19 21:06:50.069 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Error: number of retries exceeded [30].
2017-10-19 21:06:50.075 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=1, ackNum=3, reTx=true, data=04 00 34 00 00 00 00 00 01 00 00 00 40 11 00 00 04 04 05 00 00 00 01 00]
2017-10-19 21:06:50.488 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Error: number of retries exceeded [31].
2017-10-19 21:06:50.494 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=1, ackNum=3, reTx=true, data=04 00 34 00 00 00 00 00 01 00 00 00 40 11 00 00 04 04 05 00 00 00 01 00]
2017-10-19 21:06:50.908 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Error: number of retries exceeded [32].
2017-10-19 21:06:50.914 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=1, ackNum=3, reTx=true, data=04 00 34 00 00 00 00 00 01 00 00 00 40 11 00 00 04 04 05 00 00 00 01 00]
2017-10-19 21:06:51.324 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Error: number of retries exceeded [33].
2017-10-19 21:06:51.329 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=1, ackNum=3, reTx=true, data=04 00 34 00 00 00 00 00 01 00 00 00 40 11 00 00 04 04 05 00 00 00 01 00]
2017-10-19 21:06:51.739 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Error: number of retries exceeded [34].
2017-10-19 21:06:51.744 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=1, ackNum=3, reTx=true, data=04 00 34 00 00 00 00 00 01 00 00 00 40 11 00 00 04 04 05 00 00 00 01 00]
2017-10-19 21:06:52.153 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Error: number of retries exceeded [35].
2017-10-19 21:06:52.165 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=1, ackNum=3, reTx=true, data=04 00 34 00 00 00 00 00 01 00 00 00 40 11 00 00 04 04 05 00 00 00 01 00]

...


2017-10-19 21:38:43.152 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Error: number of retries exceeded [4577].
2017-10-19 21:38:43.163 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=1, ackNum=3, reTx=true, data=04 00 34 00 00 00 00 00 01 00 00 00 40 11 00 00 04 04 05 00 00 00 01 00]

and then i killed OH because shutting it down wouldn’t do anything.

Once i restarted, the other 2 nodes started up fine but the 3rd stayed at zigbee.status.offline_nodenotfound. I deleted the thing and did a rescan but it didn’t come back. So I unpaired the device and repaired it and it is now working again.

chris · October 20, 2017, 7:53pm

It looks like the coordinator stopped responding. I’m not sure why the device didn’t come back - it should rejoin. I’m looking at adding some more callbacks from the coordinator into the stack to improve this (not to say that would help here, but there are additional notifications I can use that might help).

5iver · October 21, 2017, 4:16pm

My CentraLite outlet has never dropped offline, but my GE Link bulbs will become unresponsive (but not marked offline in Habmin). Power cycling helps, but sometimes it takes a few times. After a binding or OH restart, ~80% will show as offline. Sometimes waiting will bring up a few more of them (never all), but I usually power cycle one that is offline and several will come up, and repeat until they are all online.

Sergi_Suarez · October 30, 2017, 10:43am

Hello all,
I’m just preparing to rework my appartment and will want to control as much as possible by OpenHab.
Meanwhile I’m ready for most of the things still I’m not sure if this binding currently supports ConBee/RaspBee zigwee controller.
What is the status?
Thanks a lot

chris · October 30, 2017, 11:38am

No - it is not currently supported. It’s a background task that I hope to get completed in the near future, but it’s not #1 on the list at the moment.

Right now, my recommendation is the Telegesis ETRX3. Maybe in a week or three the ConBee will also be available, but the most testing currently is with the Telegesis stick.

Sergi_Suarez · October 30, 2017, 11:54am

Thanks Chris,

Unfortunately in Spain I can only find RaspBee. 3 weeks is Ok with me as first will be the works and will take a months or so.

Thanks again

AFromD · October 31, 2017, 8:54pm

So, did all this, but my Ember stick is stuck at “initializing”
Openhab log says:

2017-10-31 21:27:41.696 [ERROR] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: BAD PACKET 11 11 07 43 A1 9C C5 2B D4 D9

How do I troubleshoot? I am using the latest nightly version for the zigbee binding , OH 2.1 release version, Ubuntu 16 on Atom PC.

chris · October 31, 2017, 9:25pm

AFromD · October 31, 2017, 9:57pm

Sorry, can’t post it all due to post size limit, I’ll do start and finish

2017-10-31 22:23:13.654 [DEBUG] [zigbee.internal.ZigBeeHandlerFactory] - Creating coordinator handler for org.eclipse.smarthome.core.thing.internal.BridgeImpl@c16d0423
2017-10-31 22:23:13.699 [DEBUG] [andler.ZigBeeCoordinatorEmberHandler] - Initializing ZigBee Ember serial bridge handler.
2017-10-31 22:23:13.700 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Initializing ZigBee network [zigbee:coordinator_ember:11d40785].
2017-10-31 22:23:13.702 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Channel -1.0
2017-10-31 22:23:13.703 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - PANID 47825.0
2017-10-31 22:23:13.704 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - EPANID F897FB25C04E8E31
2017-10-31 22:23:13.706 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Key 75 46 BA 62 AD 5F CB 12 0F BB E2 FA 9D 9D 84 AD
2017-10-31 22:23:13.707 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Key String 75 46 BA 62 AD 5F CB 12 0F BB E2 FA 9D 9D 84 AD
2017-10-31 22:23:13.708 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Key array [117, 70, 186, 98, 173, 95, 203, 18, 15, 187, 226, 250, 157, 157, 132, 173]
2017-10-31 22:23:13.709 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Key final array [117, 70, 186, 98, 173, 95, 203, 18, 15, 187, 226, 250, 157, 157, 132, 173]
2017-10-31 22:23:13.759 [DEBUG] [andler.ZigBeeCoordinatorEmberHandler] - ZigBee Coordinator Ember opening Port:'/dev/ttyUSB1' PAN:bad1, EPAN:F897FB25C04E8E31, Channel:-1
2017-10-31 22:23:13.762 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Scheduling ZigBee start
2017-10-31 22:23:13.811 [INFO ] [ding.astro.handler.AstroThingHandler] - Scheduled astro job-daily-moon at midnight for thing astro:moon:local
2017-10-31 22:23:13.833 [INFO ] [thome.binding.astro.internal.job.Job] - Scheduled Astro event-jobs for thing astro:moon:local
2017-10-31 22:23:14.763 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - ZigBee network starting
2017-10-31 22:23:14.764 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Initialising ZigBee coordinator
2017-10-31 22:23:14.767 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - Creating ZigBee discovery service for zigbee:coordinator_ember:11d40785
2017-10-31 22:23:14.767 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - Activating ZigBee discovery service for zigbee:coordinator_ember:11d40785
2017-10-31 22:23:14.777 [DEBUG] [org.openhab.binding.zigbee          ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.config.discovery.DiscoveryService}={service.id=284, service.bundleid=223, service.scope=singleton} - org.openhab.binding.zigbee
2017-10-31 22:23:14.834 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Key initialise [117, 70, 186, 98, 173, 95, 203, 18, 15, 187, 226, 250, 157, 157, 132, 173]
2017-10-31 22:23:14.838 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - EZSP dongle initialize.
2017-10-31 22:23:14.845 [INFO ] [andler.ZigBeeCoordinatorEmberHandler] - Connecting to serial port [/dev/ttyUSB1] at 57600
2017-10-31 22:23:14.956 [INFO ] [andler.ZigBeeCoordinatorEmberHandler] - Serial port [/dev/ttyUSB1] is initialized.
2017-10-31 22:23:15.013 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameRst []
2017-10-31 22:23:15.131 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - TX EZSP queue: 1
2017-10-31 22:23:15.149 [WARN ] [bee.dongle.ember.ash.AshFrameHandler] - Trying to send when not connected.
2017-10-31 22:23:15.212 [INFO ] [.dashboard.internal.DashboardService] - Started dashboard at http://192.168.189.20:8080
2017-10-31 22:23:15.214 [INFO ] [.dashboard.internal.DashboardService] - Started dashboard at https://192.168.189.20:8443
2017-10-31 22:23:16.042 [INFO ] [basic.internal.servlet.WebAppServlet] - Started Basic UI at /basicui/app
2017-10-31 22:23:16.071 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameRstAck [version=2, resetCode=11 (SOFTWARE)]
2017-10-31 22:23:16.082 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - ASH: Connected
2017-10-31 22:23:16.093 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - TX EZSP: EzspVersionRequest [desiredProtocolVersion=4]
2017-10-31 22:23:16.094 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=0, ackNum=0, reTx=false, data=01 00 00 04]
2017-10-31 22:23:16.102 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=0, ackNum=1, reTx=false, data=01 80 00 04 02 10 54]
2017-10-31 22:23:16.110 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - RX EZSP: EzspVersionResponse [protocolVersion=4, stackType=2, stackVersion=5410]
2017-10-31 22:23:16.122 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=1]
2017-10-31 22:23:16.123 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - EzspVersionResponse [protocolVersion=4, stackType=2, stackVersion=5410]
2017-10-31 22:23:16.157 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - TX EZSP queue: 1
2017-10-31 22:23:16.158 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - TX EZSP: EzspGetConfigurationValueRequest [configId=EZSP_CONFIG_MAX_HOPS]
2017-10-31 22:23:16.159 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=1, ackNum=1, reTx=false, data=02 00 52 10]
2017-10-31 22:23:16.167 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=1, ackNum=2, reTx=false, data=02 80 52 00 1E 00]
2017-10-31 22:23:16.171 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=30]
2017-10-31 22:23:16.173 [DEBUG] [ber.internal.EmberStackConfiguration] - EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=30]
2017-10-31 22:23:16.174 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - TX EZSP queue: 1
2017-10-31 22:23:16.174 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - TX EZSP: EzspGetConfigurationValueRequest [configId=EZSP_CONFIG_SECURITY_LEVEL]
2017-10-31 22:23:16.175 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=2, ackNum=1, reTx=false, data=03 00 52 0D]
2017-10-31 22:23:16.176 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=2]
2017-10-31 22:23:16.177 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2017-10-31 22:23:16.179 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameNak [ackNum=2]
2017-10-31 22:23:16.181 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=2, ackNum=2, reTx=true, data=03 00 52 0D]
2017-10-31 22:23:16.182 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2017-10-31 22:23:16.188 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameAck [ackNum=3]
2017-10-31 22:23:16.190 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=2, ackNum=3, reTx=false, data=03 80 52 00 05 00]
2017-10-31 22:23:16.191 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=5]
2017-10-31 22:23:16.193 [DEBUG] [ber.internal.EmberStackConfiguration] - EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=5]
2017-10-31 22:23:16.193 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - TX EZSP queue: 1
2017-10-31 22:23:16.194 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - TX EZSP: EzspGetConfigurationValueRequest [configId=EZSP_CONFIG_STACK_PROFILE]
2017-10-31 22:23:16.194 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=3, ackNum=2, reTx=false, data=04 00 52 0C]
2017-10-31 22:23:16.195 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=3]
2017-10-31 22:23:16.197 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2017-10-31 22:23:16.203 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameNak [ackNum=3]
2017-10-31 22:23:16.204 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=3, ackNum=3, reTx=true, data=04 00 52 0C]
2017-10-31 22:23:16.205 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2017-10-31 22:23:16.211 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameAck [ackNum=4]
2017-10-31 22:23:16.241 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=3, ackNum=4, reTx=false, data=04 80 52 00 00 00]
2017-10-31 22:23:16.241 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=0]
2017-10-31 22:23:16.246 [INFO ] [arthome.ui.paper.internal.PaperUIApp] - Started Paper UI at /paperui
2017-10-31 22:23:16.251 [DEBUG] [ber.internal.EmberStackConfiguration] - EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=0]
2017-10-31 22:23:16.251 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - TX EZSP queue: 1
2017-10-31 22:23:16.252 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - TX EZSP: EzspGetConfigurationValueRequest [configId=EZSP_CONFIG_SUPPORTED_NETWORKS]
2017-10-31 22:23:16.253 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=4, ackNum=3, reTx=false, data=05 00 52 2D]
2017-10-31 22:23:16.254 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=4]
2017-10-31 22:23:16.254 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2017-10-31 22:23:16.258 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameNak [ackNum=4]
2017-10-31 22:23:16.259 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=4, ackNum=4, reTx=true, data=05 00 52 2D]
2017-10-31 22:23:16.262 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2017-10-31 22:23:16.279 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameAck [ackNum=5]
2017-10-31 22:23:16.281 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=4, ackNum=5, reTx=false, data=05 80 52 00 02 00]
2017-10-31 22:23:16.282 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - RX EZSP: EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=2]
2017-10-31 22:23:16.287 [DEBUG] [ber.internal.EmberStackConfiguration] - EzspGetConfigurationValueResponse [status=EZSP_SUCCESS, value=2]
2017-10-31 22:23:16.288 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - TX EZSP queue: 1
2017-10-31 22:23:16.288 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - TX EZSP: EzspGetConfigurationValueRequest [configId=EZSP_CONFIG_KEY_TABLE_SIZE]
2017-10-31 22:23:16.316 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=5, ackNum=4, reTx=false, data=06 00 52 1E]
2017-10-31 22:23:16.325 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=5]

last bit

2017-10-31 22:23:19.341 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2017-10-31 22:23:19.348 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameAck [ackNum=4]
2017-10-31 22:23:19.349 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=5, ackNum=4, reTx=false, data=34 80 18 00]
2017-10-31 22:23:19.350 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - RX EZSP: EzspNetworkStateResponse [status=EMBER_NO_NETWORK]
2017-10-31 22:23:19.381 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=6]
2017-10-31 22:23:19.382 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - EzspNetworkStateResponse [status=EMBER_NO_NETWORK]
2017-10-31 22:23:19.382 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - EZSP networkStateResponse EMBER_NO_NETWORK
2017-10-31 22:23:19.382 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - TX EZSP queue: 1
2017-10-31 22:23:19.383 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - TX EZSP: EzspGetCurrentSecurityStateRequest []
2017-10-31 22:23:19.383 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=4, ackNum=6, reTx=false, data=3C 00 69]
2017-10-31 22:23:19.398 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=6, ackNum=5, reTx=false, data=3C 80 69 93 00 00 00 00 03 00 00 00 01 00]
2017-10-31 22:23:19.399 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - RX EZSP: EzspGetCurrentSecurityStateResponse [status=EMBER_NOT_JOINED, state=EmberCurrentSecurityState [bitmask=[], trustCenterLongAddress=0001000000030000]]
2017-10-31 22:23:19.405 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - EzspGetCurrentSecurityStateResponse [status=EMBER_NOT_JOINED, state=EmberCurrentSecurityState [bitmask=[], trustCenterLongAddress=0001000000030000]]
2017-10-31 22:23:19.405 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Error during retrieval of security parameters: EzspGetCurrentSecurityStateResponse [status=EMBER_NOT_JOINED, state=EmberCurrentSecurityState [bitmask=[], trustCenterLongAddress=0001000000030000]]
2017-10-31 22:23:19.406 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Current Security State = null
2017-10-31 22:23:19.406 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - EZSP dongle startup done.
2017-10-31 22:23:19.406 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=7]
2017-10-31 22:23:19.410 [DEBUG] [bee.internal.ZigBeeNetworkDiscoverer] - 0: Scheduling node discovery
2017-10-31 22:23:19.430 [DEBUG] [bee.handler.ZigBeeCoordinatorHandler] - Initialise done....... 26  65535  F897FB25C04E8E31
2017-10-31 22:23:19.439 [DEBUG] [ee.internal.ZigBeeNetworkMeshMonitor] - Starting mesh update task with interval of 86400 seconds
2017-10-31 22:23:19.459 [DEBUG] [bee.internal.ZigBeeNetworkDiscoverer] - 0: Starting node discovery
2017-10-31 22:23:19.475 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: IeeeAddressRequest [0/0 -> 0/0, cluster=0001, TID=00, nwkAddrOfInterest=0, requestType=1, startIndex=0]
2017-10-31 22:23:19.628 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - EzspSendUnicastRequest [type=EMBER_OUTGOING_DIRECT, indexOrDestination=0, apsFrame=EmberApsFrame [profileId=0, clusterId=1, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY, EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY], groupId=0, sequence=0], messageTag=0, messageContents=00 00 00 01 00]
2017-10-31 22:23:19.628 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - TX EZSP queue: 1
2017-10-31 22:23:19.628 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - TX EZSP: EzspSendUnicastRequest [type=EMBER_OUTGOING_DIRECT, indexOrDestination=0, apsFrame=EmberApsFrame [profileId=0, clusterId=1, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY, EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY], groupId=0, sequence=0], messageTag=0, messageContents=00 00 00 01 00]
2017-10-31 22:23:19.630 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=5, ackNum=7, reTx=false, data=00 00 34 00 00 00 00 00 01 00 00 00 40 11 00 00 00 00 05 00 00 00 01 00]
2017-10-31 22:23:19.639 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=7, ackNum=6, reTx=false, data=00 80 34 91 00]
2017-10-31 22:23:19.640 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - RX EZSP: EzspSendUnicastResponse [status=EMBER_NETWORK_DOWN, sequence=0]
2017-10-31 22:23:19.641 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX: EzspSendUnicastResponse [status=EMBER_NETWORK_DOWN, sequence=0]
2017-10-31 22:23:19.641 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Unhandled EZSP Frame: EzspSendUnicastResponse [status=EMBER_NETWORK_DOWN, sequence=0]
2017-10-31 22:23:19.641 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=0]
2017-10-31 22:23:20.445 [INFO ] [b.core.service.AbstractActiveService] - Energenie Refresh Service has been started
2017-10-31 22:23:20.602 [INFO ] [ulation.internal.HueEmulationServlet] - Started Hue Emulation service at /api
2017-10-31 22:23:20.776 [ERROR] [org.openhab.binding.zigbee          ] - FrameworkEvent ERROR - org.openhab.binding.zigbee
org.osgi.framework.BundleException: Could not resolve module: org.openhab.binding.zigbee [225]
  Another singleton bundle selected: osgi.identity; osgi.identity="org.openhab.binding.zigbee"; type="osgi.bundle"; version:Version="2.2.0.201710292329"; singleton:="true"

	at org.eclipse.osgi.container.Module.start(Module.java:434)[org.eclipse.osgi-3.10.101.v20150820-1432.jar:]
	at org.eclipse.osgi.container.ModuleContainer$ContainerStartLevel.incStartLevel(ModuleContainer.java:1582)[org.eclipse.osgi-3.10.101.v20150820-1432.jar:]
	at org.eclipse.osgi.container.ModuleContainer$ContainerStartLevel.incStartLevel(ModuleContainer.java:1562)[org.eclipse.osgi-3.10.101.v20150820-1432.jar:]
	at org.eclipse.osgi.container.ModuleContainer$ContainerStartLevel.doContainerStartLevel(ModuleContainer.java:1533)[org.eclipse.osgi-3.10.101.v20150820-1432.jar:]
	at org.eclipse.osgi.container.ModuleContainer$ContainerStartLevel.dispatchEvent(ModuleContainer.java:1476)[org.eclipse.osgi-3.10.101.v20150820-1432.jar:]
	at org.eclipse.osgi.container.ModuleContainer$ContainerStartLevel.dispatchEvent(ModuleContainer.java:1)[org.eclipse.osgi-3.10.101.v20150820-1432.jar:]
	at org.eclipse.osgi.framework.eventmgr.EventManager.dispatchEvent(EventManager.java:230)[org.eclipse.osgi-3.10.101.v20150820-1432.jar:]
	at org.eclipse.osgi.framework.eventmgr.EventManager$EventThread.run(EventManager.java:340)[org.eclipse.osgi-3.10.101.v20150820-1432.jar:]
2017-10-31 22:23:29.444 [DEBUG] [ee.internal.ZigBeeNetworkMeshMonitor] - 0: Starting mesh update
2017-10-31 22:23:29.444 [DEBUG] [ee.internal.ZigBeeNetworkMeshMonitor] - 0: ZigBee node not found during mesh update
2017-10-31 22:23:29.632 [DEBUG] [bee.internal.ZigBeeNetworkDiscoverer] - Ieee Address for 0 returned null
2017-10-31 22:23:29.633 [DEBUG] [bee.internal.ZigBeeNetworkDiscoverer] - 0: Discovery request IEEE_ADDRESS failed. Wait before retry.
2017-10-31 22:23:31.134 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: IeeeAddressRequest [0/0 -> 0/0, cluster=0001, TID=01, nwkAddrOfInterest=0, requestType=1, startIndex=0]
2017-10-31 22:23:31.135 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - EzspSendUnicastRequest [type=EMBER_OUTGOING_DIRECT, indexOrDestination=0, apsFrame=EmberApsFrame [profileId=0, clusterId=1, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY, EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY], groupId=0, sequence=1], messageTag=1, messageContents=00 00 00 01 00]
2017-10-31 22:23:31.135 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - TX EZSP queue: 1
2017-10-31 22:23:31.136 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - TX EZSP: EzspSendUnicastRequest [type=EMBER_OUTGOING_DIRECT, indexOrDestination=0, apsFrame=EmberApsFrame [profileId=0, clusterId=1, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY, EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY], groupId=0, sequence=1], messageTag=1, messageContents=00 00 00 01 00]
2017-10-31 22:23:31.136 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=6, ackNum=0, reTx=false, data=01 00 34 00 00 00 00 00 01 00 00 00 40 11 00 00 01 01 05 00 00 00 01 00]
2017-10-31 22:23:31.146 [ERROR] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: BAD PACKET 11 11 07 43 A1 9C C5 2B D4 D9 
2017-10-31 22:23:31.147 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameNak [ackNum=0]
2017-10-31 22:23:31.147 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2017-10-31 22:23:31.150 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameNak [ackNum=7]
2017-10-31 22:23:31.151 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=6, ackNum=0, reTx=true, data=01 00 34 00 00 00 00 00 01 00 00 00 40 11 00 00 01 01 05 00 00 00 01 00]
2017-10-31 22:23:31.151 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - Sent queue larger than window [1 > 1].
2017-10-31 22:23:31.158 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameAck [ackNum=7]
2017-10-31 22:23:31.538 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=0, ackNum=7, reTx=true, data=01 80 34 91 01]
2017-10-31 22:23:31.540 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - RX EZSP: EzspSendUnicastResponse [status=EMBER_NETWORK_DOWN, sequence=1]
2017-10-31 22:23:31.540 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX: EzspSendUnicastResponse [status=EMBER_NETWORK_DOWN, sequence=1]
2017-10-31 22:23:31.541 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Unhandled EZSP Frame: EzspSendUnicastResponse [status=EMBER_NETWORK_DOWN, sequence=1]
2017-10-31 22:23:31.541 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=1]
2017-10-31 22:23:41.138 [DEBUG] [bee.internal.ZigBeeNetworkDiscoverer] - Ieee Address for 0 returned null
2017-10-31 22:23:41.140 [DEBUG] [bee.internal.ZigBeeNetworkDiscoverer] - 0: Discovery request IEEE_ADDRESS failed. Wait before retry.
2017-10-31 22:23:42.642 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: IeeeAddressRequest [0/0 -> 0/0, cluster=0001, TID=02, nwkAddrOfInterest=0, requestType=1, startIndex=0]
2017-10-31 22:23:42.643 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - EzspSendUnicastRequest [type=EMBER_OUTGOING_DIRECT, indexOrDestination=0, apsFrame=EmberApsFrame [profileId=0, clusterId=1, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY, EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY], groupId=0, sequence=2], messageTag=2, messageContents=00 00 00 01 00]
2017-10-31 22:23:42.644 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - TX EZSP queue: 1
2017-10-31 22:23:42.645 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - TX EZSP: EzspSendUnicastRequest [type=EMBER_OUTGOING_DIRECT, indexOrDestination=0, apsFrame=EmberApsFrame [profileId=0, clusterId=1, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY, EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY], groupId=0, sequence=2], messageTag=2, messageContents=00 00 00 01 00]
2017-10-31 22:23:42.647 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=7, ackNum=1, reTx=false, data=02 00 34 00 00 00 00 00 01 00 00 00 40 11 00 00 02 02 05 00 00 00 01 00]
2017-10-31 22:23:42.658 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=1, ackNum=0, reTx=false, data=02 80 34 91 02]
2017-10-31 22:23:42.658 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - RX EZSP: EzspSendUnicastResponse [status=EMBER_NETWORK_DOWN, sequence=2]
2017-10-31 22:23:42.658 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX: EzspSendUnicastResponse [status=EMBER_NETWORK_DOWN, sequence=2]
2017-10-31 22:23:42.658 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Unhandled EZSP Frame: EzspSendUnicastResponse [status=EMBER_NETWORK_DOWN, sequence=2]
2017-10-31 22:23:42.658 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=2]
2017-10-31 22:23:52.650 [DEBUG] [bee.internal.ZigBeeNetworkDiscoverer] - Ieee Address for 0 returned null
2017-10-31 22:23:52.651 [DEBUG] [bee.internal.ZigBeeNetworkDiscoverer] - 0: Discovery request IEEE_ADDRESS failed. Wait before retry.
2017-10-31 22:23:54.152 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: IeeeAddressRequest [0/0 -> 0/0, cluster=0001, TID=03, nwkAddrOfInterest=0, requestType=1, startIndex=0]
2017-10-31 22:23:54.154 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - EzspSendUnicastRequest [type=EMBER_OUTGOING_DIRECT, indexOrDestination=0, apsFrame=EmberApsFrame [profileId=0, clusterId=1, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY, EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY], groupId=0, sequence=3], messageTag=3, messageContents=00 00 00 01 00]
2017-10-31 22:23:54.154 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - TX EZSP queue: 1
2017-10-31 22:23:54.155 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - TX EZSP: EzspSendUnicastRequest [type=EMBER_OUTGOING_DIRECT, indexOrDestination=0, apsFrame=EmberApsFrame [profileId=0, clusterId=1, sourceEndpoint=0, destinationEndpoint=0, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY, EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY], groupId=0, sequence=3], messageTag=3, messageContents=00 00 00 01 00]
2017-10-31 22:23:54.158 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=0, ackNum=2, reTx=false, data=03 00 34 00 00 00 00 00 01 00 00 00 40 11 00 00 03 03 05 00 00 00 01 00]
2017-10-31 22:23:54.169 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=2, ackNum=1, reTx=false, data=03 80 34 91 03]
2017-10-31 22:23:54.170 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - RX EZSP: EzspSendUnicastResponse [status=EMBER_NETWORK_DOWN, sequence=3]
2017-10-31 22:23:54.170 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - RX: EzspSendUnicastResponse [status=EMBER_NETWORK_DOWN, sequence=3]
2017-10-31 22:23:54.170 [DEBUG] [zigbee.dongle.ember.ZigBeeDongleEzsp] - Unhandled EZSP Frame: EzspSendUnicastResponse [status=EMBER_NETWORK_DOWN, sequence=3]
2017-10-31 22:23:54.171 [DEBUG] [bee.dongle.ember.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=3]
2017-10-31 22:24:04.161 [DEBUG] [bee.internal.ZigBeeNetworkDiscoverer] - Ieee Address for 0 returned null
2017-10-31 22:24:04.162 [DEBUG] [bee.internal.ZigBeeNetworkDiscoverer] - 0: Discovery request IEEE_ADDRESS failed. Wait before retry.
2017-10-31 22:24:05.665 [DEBUG] [bee.internal.ZigBeeNetworkDiscoverer] - 0: Ending node discovery

stopherd · November 1, 2017, 11:12pm

What hardware & Software are you using? It took me a few tries to get it going on openhabian & found part of the problem was getting the wrong versions together.

AFromD · November 2, 2017, 5:41pm

Do you mean me?

puzzle-star · November 6, 2017, 12:57am

Hi all,

New to OpenHAB, after playing around a bit with SmartThings (the IKEA RGB bulb device handler for ST metioned above is mine )

For a number of reasons (local vs cloud, zigbee support from ST not being up to date, etc…) I have decided to give OpenHAB a try, and after a bit of “suffering” it is more or less starting to work (OH 2.1, Zigbee binding 2.2)

I am using a TI CC2530 connected to a raspberry through a USB to Serial converter. The first issue I have found is that there seems to be no way to change the baud rate in the Zigbee binding for CC2531, and it seems to be initializing the serial port at 230400 baud, while CC2530 TI default firmware comes preconfigured at 115200. Could I gently ask for a setting to be added to be able to configure the serial baud rate, for those not using CC2531 but CC2530 or any other variant instead ?

Meanwhile I have worked it around by developing a small forwarder tool that creates a virtual serial port and just moves data in and out, but of course this is not the way to go in the long term (anyway, will be happy to share it if somebody is interested)

The second thing I am seing (at least with the first device I have paired: an Osram Switch) is that reporting does not seem to be working. Is there some way to configure it ? I can help on the development if needed, as I already did some DHs for ST, and I can “tweak a bit” both on Java and Zigbee

My target is to move my ST stuff (around 50 IKEA, Osram, and other zigbee devices) to OH. I liked how easy it was in ST to develop apps and DHs, anyway, let’s see what about it in OH…

jace · November 9, 2017, 6:35pm

I suppose I might jump in this thread instead of starting a new one. I have the HUSBZB-1, and an having issue getting the zigbee side working. I’m running openHab 2.2 (1073) on Windows 10. I used the Silicon Labs Dual CP2105 USB to UART Bridge drivers. For the zigbee com port, I’ve tried both the Enhanced and Standard driver versions.

In openHab, I set the baud rate to 57600. It communicates with the device, but I get an error I’ve seen above in this thread:

2017-11-09 10:19:25.993 [BeeCoordinatorEmberHandler] - Connecting to serial port [COM4] at 57600
2017-11-09 10:19:27.371 [BeeCoordinatorEmberHandler] - Serial port [COM4] is initialized.
2017-11-09 10:19:27.415 [WARN ] [bee.dongle.ember.ash.AshFrameHandler] - Trying to send when not connected.
2017-11-09 10:19:28.849 [r.ZigBeeCoordinatorHandler] - initResponse is NOT_JOINED
2017-11-09 10:19:28.849 [r.ZigBeeCoordinatorHandler] - initializeNetwork is true
2017-11-09 10:19:30.530 [r.ZigBeeCoordinatorHandler] - Initialise done....... 12  65535  5F80DA1F22912AB8
2017-11-09 10:19:42.059 [ERROR] [bee.dongle.ember.ash.AshFrameHandler] - <-- RX ASH frame: BAD PACKET 11 11 07 43 A1 9C C5 2B D4 D9

Then it stops trying. Has anyone seen / resolved this?

Thanks!

chris · November 9, 2017, 7:11pm

The first thing I would suggest is to wait a few days and then update. The build has been broken for the past few months - a PR was merged this morning that will (I hope!) fix this and I’ll do some updates to the binding over the next few days to bring everything up to date.

If that doesn’t fix it, then we’ll need a bit more information from the log. I guess you only have error/warning logging enabled - it would be good to see the full debug log.

There is an issue with the Ember that I’ve not managed to find (and neither could Silabs at the time I was hunting for it) - I don’t know if this is what you have hit, but let’s see what the above brings first.

laohixdxm2016 · November 9, 2017, 7:34pm

@stopherd @5iver @chris

Hi,

It looks like something is working. Just want to make sure what it is…

Am I correct to say
1 the Linear HUSBZB-1 is compatible with openhab zigbee binding.
2 Only configuration on openhab side is required.
3 Dongle firmware is already programmed so that we do NOT need to do manual firmware programming as for TI 2531.

Thank you very much.

stopherd · November 9, 2017, 7:47pm

Worked for me straight out of the box from Amazon.

5iver · November 9, 2017, 7:51pm

Yes, for the snapshot version of the binding which has a baud rate setting
Yes, but there may be some permissions to play with in the OS too
Yes, although the firmware could be updated

If you search the forum for HUSBZB-1, you’ll find a few topics about it.

jace · November 9, 2017, 7:57pm

Sounds good. I do have debugging enabled for zigbee to a separate log, and there is a bunch of stuff happening before the point I posted. Initializing, setting keys, etc. I’ll update when I’ve gotten a change to try the updated build. Thanks!