Zigbee binding

I just started fresh now. When adding the coordinator, I tuned on initialization and entered the PAN ID I used with zigbee4java. I also selected channel 11. I didn’t enter a encryption key or the extended PAN ID.

When trying to change the config, a different PAN ID I entered (but a random key and an extended PAN ID, init is disabled again).

From the logs:
2017-05-05 23:02:33.489 [ThingAddedEvent ] - Thing ‘zigbee:coordinator_cc2531:b689b918’ has been added.
2017-05-05 23:02:33.565 [hingStatusInfoChangedEvent] - ‘zigbee:coordinator_cc2531:b689b918’ changed from UNINITIALIZED to INITIALIZING
2017-05-05 23:02:33.646 [ThingUpdatedEvent ] - Thing ‘zigbee:coordinator_cc2531:b689b918’ has been updated.
2017-05-05 23:02:33.672 [ThingUpdatedEvent ] - Thing ‘zigbee:coordinator_cc2531:b689b918’ has been updated.
2017-05-05 23:02:33.700 [ThingUpdatedEvent ] - Thing ‘zigbee:coordinator_cc2531:b689b918’ has been updated.
2017-05-05 23:02:33.746 [ThingUpdatedEvent ] - Thing ‘zigbee:coordinator_cc2531:b689b918’ has been updated.
2017-05-05 23:02:33.761 [hingStatusInfoChangedEvent] - ‘zigbee:coordinator_cc2531:b689b918’ changed from INITIALIZING to OFFLINE (BRIDGE_OFFLINE): ZigBee transport layer not yet initialized
2017-05-05 23:02:42.453 [hingStatusInfoChangedEvent] - ‘zigbee:coordinator_cc2531:b689b918’ changed from OFFLINE (BRIDGE_OFFLINE): ZigBee transport layer not yet initialized to ONLINE
2017-05-05 23:02:42.492 [ThingUpdatedEvent ] - Thing ‘zigbee:coordinator_cc2531:b689b918’ has been updated.

2017-05-05 23:02:35.116 [INFO ] [ndler.ZigBeeCoordinatorCC2531Handler] - Serial port [/dev/ttyACM0] is initialized.

Here’s the config now:

  "zigbee:coordinator_cc2531:b689b918": {
    "class": "org.eclipse.smarthome.core.thing.internal.BridgeImpl",
    "value": {
      "label": "CC2531EMK Coordinator",
      "channels": [],
      "configuration": {
        "properties": { 
          "zigbee_port": "/dev/ttyACM0",
          "zigbee_channel": 11,
          "zigbee_initialise": false,
          "zigbee_extendedpanid": "33F403F3C29C4000",
          "zigbee_networkkey": "ED 1B DB D2 7B 8D BD F7 1B FC AF 7D 55 21 A2 D4",
          "zigbee_panid": 6423
        }
      }, 
      "properties": {},
      "uid": {
        "segments": [
          "zigbee",  
          "coordinator_cc2531",
          "b689b918"
        ]
      }, 
      "thingTypeUID": {
        "segments": [  
          "zigbee",    
          "coordinator_cc2531"
        ]
      }  
    }    
  },     

It’s displayed as online now, but when trying to pair a sensor, it doesn’t seem to work. Currently, I’m only seeing the packets from the sensor, but not the coordinator. RF seems to be a bit bad here (openhab server isn’t located at my desk…). But I don’t see the status update packets from the sensor, therefore I guess the pairing didn’t work.
Any ideas how to debug this further?

When zigbee4java worked, I used a specific PAN ID and network key was all zeroes. Setting this PAN ID somehow didn’t work…

What does “tuned on initialization” mean?

Please can you enable debug logging.

If you want to use the existing network, then you don’t need to configure anything. I think this point is linked to the first question - ie what does “tuned on initialization” mean? Are you trying to initialise the dongle complete (ie setting the Reset Controller to true)? If so, then it will reset everything.

So, to try and explain a bit more - if you select “Reset Controller” - it will “Resets the Controller and sets the configuration to the configured values.”. This will use the values you enter. If you don’t set it to reset, then it will use the existing data on the dongle.

“Tuned on initialization” should have meant “turned on”, sorry for the typo. Anyway, I started all over again now that I realized what the “reset controller” option is meant to be.

Before I started again, I entered the parameters that the addon generated into the zigbee4java gateway, just to make sure the sensors work with that config (and that I have the right firmware). That works; I can see the notifications from the sensors.

So, back to openhab2: I installed a fresh snapshot on my workstation and your zigbee addon. I started up karaf with the “debug” argument. I added a thing, selected “reset controller”. That’s what my config ended up being:

“configuration”: {
“properties”: {
“zigbee_port”: “/dev/ttyACM0”,
“zigbee_channel”: 11,
“zigbee_initialise”: false,
“zigbee_panid”: 19161,
“zigbee_extendedpanid”: “33F403F3C29C4000”,
“zigbee_networkkey”: “ED 1B DB D2 7B 8D BD F7 1B FC AF 7D 55 21 A2 D4”
}
},

The logs seem to indicate that everything went fine and the coordinator is online in the GUI.

But the communication with the sensors fails. Do you need a packet dump from the TI sniffer? Or is there anything more to enable debugging besides “karaf debug”?

That’s all I get in the logs:

==> userdata/logs/openhab.log <==
2017-05-07 14:00:19.756 [INFO ] [.dashboard.internal.DashboardService] - Started dashboard at /start
2017-05-07 14:00:20.007 [INFO ] [basic.internal.servlet.WebAppServlet] - Started Basic UI at /basicui/app
2017-05-07 14:00:20.098 [INFO ] [arthome.ui.paper.internal.PaperUIApp] - Started Paper UI at /paperui
2017-05-07 14:00:20.139 [INFO ] [panel.internal.HABPanelDashboardTile] - Started HABPanel at /habpanel

==> userdata/logs/events.log <==
2017-05-07 14:00:20.242 [hingStatusInfoChangedEvent] - 'zigbee:coordinator_cc2531:977a2a62' changed from UNINITIALIZED to INITIALIZING
2017-05-07 14:00:20.244 [ThingUpdatedEvent         ] - Thing 'zigbee:coordinator_cc2531:977a2a62' has been updated.
2017-05-07 14:00:20.256 [hingStatusInfoChangedEvent] - 'zigbee:coordinator_cc2531:977a2a62' changed from INITIALIZING to OFFLINE (BRIDGE_OFFLINE): ZigBee transport layer not yet initialized

==> userdata/logs/openhab.log <==
2017-05-07 14:00:21.357 [INFO ] [ndler.ZigBeeCoordinatorCC2531Handler] - Serial port [/dev/ttyACM0] is initialized.

==> userdata/logs/events.log <==
2017-05-07 14:00:27.338 [hingStatusInfoChangedEvent] - 'zigbee:coordinator_cc2531:977a2a62' changed from OFFLINE (BRIDGE_OFFLINE): ZigBee transport layer not yet initialized to ONLINE
2017-05-07 14:00:27.346 [ThingUpdatedEvent         ] - Thing 'zigbee:coordinator_cc2531:977a2a62' has been updated.

It doesn’t look like you’ve enabled any debugging in the log? I can only see INFO messages.

http://docs.openhab.org/administration/logging.html

log.set DEBUG org.openhab.binding.zigbee

and

log.set DEBUG com.zsmartsystems.zigbee

should help.

What are the “sensors” you are talking about?

Sorry, I guess I mentioned them in a different thread. I’m trying out the Xiaomi Mi Smart Home Wireless Switch, Door/Window Sensor and Temperature/Humidity Sensor. As mentioned earlier, they send Attribute Reports with the Secure_LinkKeyJoin.hex firmware from TI’s Z-Stack Home 1.2.2a.44539.

Debug logging is now enabled. The log to 40k, so I posted it to pastebin: https://pastebin.com/BvAnDdEA

I tried to pair the door/window sensor twice, at around 17:15:23 and 17:16:00
Here’s the log around that:

2017-05-07 17:15:18.339 [DEBUG] [bee.internal.ZigBeeNetworkDiscoverer] - 0: Discovery request IEEE_ADDRESS failed. Wait before retry.
2017-05-07 17:15:19.839 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: IeeeAddressRequest [0/0 -> 0/0, cluster=0001, TID=02, nwkAddrOfInterest=0, requestType=1, startIndex=0]
2017-05-07 17:15:19.839 [DEBUG] [31.network.impl.CommandInterfaceImpl] - ->  AF_DATA_REQUEST (Packet: length=15, apiId=24 01, data=FE 0F 24 01 00 00 00 00 01 00 02 30 1F 05 00 00 00 01 00 02, checksum=02, error=false) 
2017-05-07 17:15:19.848 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-  AF_DATA_SRSP (FE 01 64 01 00 64)
2017-05-07 17:15:19.857 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- ZToolPacket (FE 18 45 FF 00 00 00 01 80 00 00 00 00 00 93 0B EB 09 00 4B 12 00 00 00 01 00 EA 26 CD)
2017-05-07 17:15:19.857 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: length=24, apiId=45 FF, data=FE 18 45 FF 00 00 00 01 80 00 00 00 00 00 93 0B EB 09 00 4B 12 00 00 00 01 00 EA 26 CD, checksum=CD, error=false
2017-05-07 17:15:19.857 [DEBUG] [e.dongle.cc2531.ZigBeeDongleTiCc2531] - Unhandled ZToolPacket type 0x45ff
2017-05-07 17:15:19.857 [DEBUG] [e.dongle.cc2531.ZigBeeDongleTiCc2531] - ZToolPacket packet not processed
2017-05-07 17:15:29.843 [DEBUG] [bee.internal.ZigBeeNetworkDiscoverer] - Ieee Address for 0 returned null
2017-05-07 17:15:29.844 [DEBUG] [bee.internal.ZigBeeNetworkDiscoverer] - 0: Discovery request IEEE_ADDRESS failed. Wait before retry.
2017-05-07 17:15:31.344 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: IeeeAddressRequest [0/0 -> 0/0, cluster=0001, TID=03, nwkAddrOfInterest=0, requestType=1, startIndex=0]
2017-05-07 17:15:31.344 [DEBUG] [31.network.impl.CommandInterfaceImpl] - ->  AF_DATA_REQUEST (Packet: length=15, apiId=24 01, data=FE 0F 24 01 00 00 00 00 01 00 03 30 1F 05 00 00 00 01 00 03, checksum=03, error=false) 
2017-05-07 17:15:31.353 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-  AF_DATA_SRSP (FE 01 64 01 00 64)
2017-05-07 17:15:31.361 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- ZToolPacket (FE 18 45 FF 00 00 00 01 80 00 00 00 00 00 93 0B EB 09 00 4B 12 00 00 00 01 00 EA 26 CD)
2017-05-07 17:15:31.361 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: length=24, apiId=45 FF, data=FE 18 45 FF 00 00 00 01 80 00 00 00 00 00 93 0B EB 09 00 4B 12 00 00 00 01 00 EA 26 CD, checksum=CD, error=false
2017-05-07 17:15:31.361 [DEBUG] [e.dongle.cc2531.ZigBeeDongleTiCc2531] - Unhandled ZToolPacket type 0x45ff
2017-05-07 17:15:31.362 [DEBUG] [e.dongle.cc2531.ZigBeeDongleTiCc2531] - ZToolPacket packet not processed
2017-05-07 17:15:41.348 [DEBUG] [bee.internal.ZigBeeNetworkDiscoverer] - Ieee Address for 0 returned null
2017-05-07 17:15:41.348 [DEBUG] [bee.internal.ZigBeeNetworkDiscoverer] - 0: Discovery request IEEE_ADDRESS failed. Wait before retry.
2017-05-07 17:15:42.849 [DEBUG] [bee.internal.ZigBeeNetworkDiscoverer] - 0: Ending node discovery
2017-05-07 17:16:03.812 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- ZToolPacket (FE 0C 45 CA EA 26 CE 36 58 01 00 8D 15 00 00 00 76)
2017-05-07 17:16:03.813 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: length=12, apiId=45 CA, data=FE 0C 45 CA EA 26 CE 36 58 01 00 8D 15 00 00 00 76, checksum=76, error=false
2017-05-07 17:16:03.814 [DEBUG] [e.dongle.cc2531.ZigBeeDongleTiCc2531] - Unhandled ZToolPacket type 0x45ca
2017-05-07 17:16:03.814 [DEBUG] [e.dongle.cc2531.ZigBeeDongleTiCc2531] - ZToolPacket packet not processed
2017-05-07 17:16:06.823 [DEBUG] [ee.internal.ZigBeeNetworkMeshMonitor] - 0: Starting mesh update
2017-05-07 17:16:06.823 [DEBUG] [ee.internal.ZigBeeNetworkMeshMonitor] - 0: ZigBee node not found during mesh update
2017-05-07 17:16:06.823 [DEBUG] [bee.internal.ZigBeeNetworkDiscoverer] - 0: Scheduling node discovery
2017-05-07 17:16:06.823 [DEBUG] [bee.internal.ZigBeeNetworkDiscoverer] - 0: Starting node discovery
2017-05-07 17:16:06.824 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: IeeeAddressRequest [0/0 -> 0/0, cluster=0001, TID=04, nwkAddrOfInterest=0, requestType=1, startIndex=0]
2017-05-07 17:16:06.824 [DEBUG] [31.network.impl.CommandInterfaceImpl] - ->  AF_DATA_REQUEST (Packet: length=15, apiId=24 01, data=FE 0F 24 01 00 00 00 00 01 00 04 30 1F 05 00 00 00 01 00 04, checksum=04, error=false) 
2017-05-07 17:16:06.833 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-  AF_DATA_SRSP (FE 01 64 01 00 64)
2017-05-07 17:16:06.841 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- ZToolPacket (FE 18 45 FF 00 00 00 01 80 00 00 00 00 00 93 0B EB 09 00 4B 12 00 00 00 01 00 EA 26 CD)
2017-05-07 17:16:06.841 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: length=24, apiId=45 FF, data=FE 18 45 FF 00 00 00 01 80 00 00 00 00 00 93 0B EB 09 00 4B 12 00 00 00 01 00 EA 26 CD, checksum=CD, error=false
2017-05-07 17:16:06.841 [DEBUG] [e.dongle.cc2531.ZigBeeDongleTiCc2531] - Unhandled ZToolPacket type 0x45ff
2017-05-07 17:16:06.841 [DEBUG] [e.dongle.cc2531.ZigBeeDongleTiCc2531] - ZToolPacket packet not processed
2017-05-07 17:16:08.487 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- ZToolPacket (FE 0C 45 CA EA 26 CE 36 58 01 00 8D 15 00 00 00 76)
2017-05-07 17:16:08.487 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: length=12, apiId=45 CA, data=FE 0C 45 CA EA 26 CE 36 58 01 00 8D 15 00 00 00 76, checksum=76, error=false
2017-05-07 17:16:08.487 [DEBUG] [e.dongle.cc2531.ZigBeeDongleTiCc2531] - Unhandled ZToolPacket type 0x45ca
2017-05-07 17:16:08.487 [DEBUG] [e.dongle.cc2531.ZigBeeDongleTiCc2531] - ZToolPacket packet not processed
2017-05-07 17:16:16.828 [DEBUG] [bee.internal.ZigBeeNetworkDiscoverer] - Ieee Address for 0 returned null
2017-05-07 17:16:16.828 [DEBUG] [bee.internal.ZigBeeNetworkDiscoverer] - 0: Discovery request IEEE_ADDRESS failed. Wait before retry.
2017-05-07 17:16:18.328 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: IeeeAddressRequest [0/0 -> 0/0, cluster=0001, TID=05, nwkAddrOfInterest=0, requestType=1, startIndex=0]
2017-05-07 17:16:18.329 [DEBUG] [31.network.impl.CommandInterfaceImpl] - ->  AF_DATA_REQUEST (Packet: length=15, apiId=24 01, data=FE 0F 24 01 00 00 00 00 01 00 05 30 1F 05 00 00 00 01 00 05, checksum=05, error=false) 
2017-05-07 17:16:18.338 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-  AF_DATA_SRSP (FE 01 64 01 00 64)
2017-05-07 17:16:18.346 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- ZToolPacket (FE 18 45 FF 00 00 00 01 80 00 00 00 00 00 93 0B EB 09 00 4B 12 00 00 00 01 00 EA 26 CD)
2017-05-07 17:16:18.346 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: length=24, apiId=45 FF, data=FE 18 45 FF 00 00 00 01 80 00 00 00 00 00 93 0B EB 09 00 4B 12 00 00 00 01 00 EA 26 CD, checksum=CD, error=false
2017-05-07 17:16:18.346 [DEBUG] [e.dongle.cc2531.ZigBeeDongleTiCc2531] - Unhandled ZToolPacket type 0x45ff
2017-05-07 17:16:18.346 [DEBUG] [e.dongle.cc2531.ZigBeeDongleTiCc2531] - ZToolPacket packet not processed
2017-05-07 17:16:28.333 [DEBUG] [bee.internal.ZigBeeNetworkDiscoverer] - Ieee Address for 0 returned null
2017-05-07 17:16:28.333 [DEBUG] [bee.internal.ZigBeeNetworkDiscoverer] - 0: Discovery request IEEE_ADDRESS failed. Wait before retry.
2017-05-07 17:16:29.833 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: IeeeAddressRequest [0/0 -> 0/0, cluster=0001, TID=06, nwkAddrOfInterest=0, requestType=1, startIndex=0]
2017-05-07 17:16:29.833 [DEBUG] [31.network.impl.CommandInterfaceImpl] - ->  AF_DATA_REQUEST (Packet: length=15, apiId=24 01, data=FE 0F 24 01 00 00 00 00 01 00 06 30 1F 05 00 00 00 01 00 06, checksum=06, error=false) 
2017-05-07 17:16:29.843 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-  AF_DATA_SRSP (FE 01 64 01 00 64)
2017-05-07 17:16:29.851 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- ZToolPacket (FE 18 45 FF 00 00 00 01 80 00 00 00 00 00 93 0B EB 09 00 4B 12 00 00 00 01 00 EA 26 CD)
2017-05-07 17:16:29.851 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: length=24, apiId=45 FF, data=FE 18 45 FF 00 00 00 01 80 00 00 00 00 00 93 0B EB 09 00 4B 12 00 00 00 01 00 EA 26 CD, checksum=CD, error=false
2017-05-07 17:16:29.851 [DEBUG] [e.dongle.cc2531.ZigBeeDongleTiCc2531] - Unhandled ZToolPacket type 0x45ff
2017-05-07 17:16:29.851 [DEBUG] [e.dongle.cc2531.ZigBeeDongleTiCc2531] - ZToolPacket packet not processed
2017-05-07 17:16:39.838 [DEBUG] [bee.internal.ZigBeeNetworkDiscoverer] - Ieee Address for 0 returned null
2017-05-07 17:16:39.838 [DEBUG] [bee.internal.ZigBeeNetworkDiscoverer] - 0: Discovery request IEEE_ADDRESS failed. Wait before retry.
2017-05-07 17:16:41.340 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: IeeeAddressRequest [0/0 -> 0/0, cluster=0001, TID=07, nwkAddrOfInterest=0, requestType=1, startIndex=0]
2017-05-07 17:16:41.340 [DEBUG] [31.network.impl.CommandInterfaceImpl] - ->  AF_DATA_REQUEST (Packet: length=15, apiId=24 01, data=FE 0F 24 01 00 00 00 00 01 00 07 30 1F 05 00 00 00 01 00 07, checksum=07, error=false) 
2017-05-07 17:16:41.349 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-  AF_DATA_SRSP (FE 01 64 01 00 64)
2017-05-07 17:16:41.357 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- ZToolPacket (FE 18 45 FF 00 00 00 01 80 00 00 00 00 00 93 0B EB 09 00 4B 12 00 00 00 01 00 EA 26 CD)
2017-05-07 17:16:41.357 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: length=24, apiId=45 FF, data=FE 18 45 FF 00 00 00 01 80 00 00 00 00 00 93 0B EB 09 00 4B 12 00 00 00 01 00 EA 26 CD, checksum=CD, error=false
2017-05-07 17:16:41.357 [DEBUG] [e.dongle.cc2531.ZigBeeDongleTiCc2531] - Unhandled ZToolPacket type 0x45ff
2017-05-07 17:16:41.358 [DEBUG] [e.dongle.cc2531.ZigBeeDongleTiCc2531] - ZToolPacket packet not processed

Ah - ok, sorry, I reply to so many messages on here about different sensors etc that I loose track.

The log looks a bit strange - like it’s running some old code so I might have linked with an incorrect version of the libraries in this version. I’m flicking back and forward between the Ember and TI dongle at the moment which is a bit of a nightmare…

Never mind. I’m still not sure where I mentioned those sensors in the first place.

Are you planning on building a new snapshot build then? Please don’t feel forced to do so. I can see how much hassle it is to debug two different dongles at the same time! Btw: On some startups I can see that the binding fails to reset the dongle and tries to send the magic packet to the bootloader, but can’t recover from there. Unplugging the dongle and restarting helps.

I remember the discussion - somewhere ;).

Yes - I’ll take a look at it a bit later. It’s definitely running the wrong library - ie before I (hopefully!) fixed the TI startup issue.

Yeah, but that’s why I need to get the TI working properly so we’re only having to deal with common parts of the code…

Yes - this was the problem I had. The startup wasn’t configuring a couple of parameters and it crashed the dongle. This is fixed now so hopefully it’s just a case of sorting out the libraries and recompiling the binding.

I’ve updated the binding and tested it for a few hours with a Hue bulb - let’s see if it works better - if not, let me know and I’ll take a look.

@chris > A small word of warning though - these TI dongles may not come with appropriate firmware loaded, and getting this loaded can be painful.

Oh yes, that’s quite true. Thanks for this warning. Without this I would have given up sooner :wink:

Anyway. I’ve finally flashed the firmware on the CC2531 and it is recognized by the binding.
For the first tests, I resetted one of my Osram Lightify bulbs and started zigbee discovery through PaperUI->Inbox->Search for things → Zigbee

Unfortunately, the bulb isn’t found. That’s the debug output of it:

2017-05-09 19:28:54.131 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - Starting ZigBee scan for zigbee:coordinator_cc2531:af6da79d
2017-05-09 19:28:54.134 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - Permit join for 60 seconds.
2017-05-09 19:28:54.138 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ManagementPermitJoiningRequest [0/0 -> 65532/0, cluster=0036, TID=45, permitDuration=60, tcSignificance=true]
2017-05-09 19:28:54.141 [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 45 30 1F 03 00 3C 01 49, checksum=49, error=false)
2017-05-09 19:28:54.162 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-  AF_DATA_SRSP (FE 01 64 01 00 64)
2017-05-09 19:29:02.372 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- ZToolPacket (FE 14 45 FF 04 EB 01 13 00 00 00 FF FF 04 EB 9B 95 D9 00 00 26 18 84 0E DF)
2017-05-09 19:29:02.375 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=20, apiId=45 FF, data=FE 14 45 FF 04 EB 01 13 00 00 00 FF FF 04 EB 9B 95 D9 00 00 26 18 84 0E DF, checksum=DF, error=false
2017-05-09 19:29:02.378 [DEBUG] [e.dongle.cc2531.ZigBeeDongleTiCc2531] - Unhandled ZToolPacket type 0x45ff
2017-05-09 19:29:02.381 [DEBUG] [e.dongle.cc2531.ZigBeeDongleTiCc2531] - ZToolPacket packet not processed
2017-05-09 19:29:34.902 [DEBUG] [ee.internal.ZigBeeNetworkMeshMonitor] - 0: Starting mesh update
2017-05-09 19:29:34.904 [DEBUG] [ee.internal.ZigBeeNetworkMeshMonitor] - 0: ZigBee node not found during mesh update
2017-05-09 19:29:34.908 [DEBUG] [bee.internal.ZigBeeNetworkDiscoverer] - 0: Scheduling node discovery
2017-05-09 19:29:34.912 [DEBUG] [bee.internal.ZigBeeNetworkDiscoverer] - 0: Starting node discovery
2017-05-09 19:29:34.916 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: IeeeAddressRequest [0/0 -> 0/0, cluster=0001, TID=46, nwkAddrOfInterest=0, requestType=1, startIndex=0]
2017-05-09 19:29:34.920 [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 46 30 1F 05 00 00 00 01 00 46, checksum=46, error=false)
2017-05-09 19:29:34.943 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-  AF_DATA_SRSP (FE 01 64 01 00 64)
2017-05-09 19:29:34.954 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- ZToolPacket (FE 18 45 FF 00 00 00 01 80 00 00 00 00 00 29 D3 68 07 00 4B 12 00 00 00 01 00 04 EB 01)
2017-05-09 19:29:34.957 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=24, apiId=45 FF, data=FE 18 45 FF 00 00 00 01 80 00 00 00 00 00 29 D3 68 07 00 4B 12 00 00 00 01 00 04 EB 01, checksum=01, error=false
2017-05-09 19:29:34.959 [DEBUG] [e.dongle.cc2531.ZigBeeDongleTiCc2531] - Unhandled ZToolPacket type 0x45ff
2017-05-09 19:29:34.962 [DEBUG] [e.dongle.cc2531.ZigBeeDongleTiCc2531] - ZToolPacket packet not processed
2017-05-09 19:29:44.936 [DEBUG] [bee.internal.ZigBeeNetworkDiscoverer] - Ieee Address for 0 returned null
2017-05-09 19:29:44.939 [DEBUG] [bee.internal.ZigBeeNetworkDiscoverer] - 0: Discovery request IEEE_ADDRESS failed. Wait before retry.
2017-05-09 19:29:46.443 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: IeeeAddressRequest [0/0 -> 0/0, cluster=0001, TID=47, nwkAddrOfInterest=0, requestType=1, startIndex=0]
2017-05-09 19:29:46.446 [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 47 30 1F 05 00 00 00 01 00 47, checksum=47, error=false)
2017-05-09 19:29:46.470 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-  AF_DATA_SRSP (FE 01 64 01 00 64)
2017-05-09 19:29:46.480 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- ZToolPacket (FE 18 45 FF 00 00 00 01 80 00 00 00 00 00 29 D3 68 07 00 4B 12 00 00 00 01 00 04 EB 01)
2017-05-09 19:29:46.483 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=24, apiId=45 FF, data=FE 18 45 FF 00 00 00 01 80 00 00 00 00 00 29 D3 68 07 00 4B 12 00 00 00 01 00 04 EB 01, checksum=01, error=false
2017-05-09 19:29:46.486 [DEBUG] [e.dongle.cc2531.ZigBeeDongleTiCc2531] - Unhandled ZToolPacket type 0x45ff
2017-05-09 19:29:46.488 [DEBUG] [e.dongle.cc2531.ZigBeeDongleTiCc2531] - ZToolPacket packet not processed

Is the Osram bulb doing something wrong or is it just not supported (yet)?
Should I reset one of my HUE bulbs to check, if these are found?

Any idea how to proceed from here @chris?

Osram bulbs should work ok if they are ZLL bulbs - of course they might need resetting before they will join (I don’t have one to test with, but this is normal).

The strange thing in the log is this -:

2017-05-09 19:29:46.443 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: IeeeAddressRequest [0/0 -> 0/0, cluster=0001, TID=47, nwkAddrOfInterest=0, requestType=1, startIndex=0]
2017-05-09 19:29:34.943 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-  AF_DATA_SRSP (FE 01 64 01 00 64)
2017-05-09 19:29:34.954 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- ZToolPacket (FE 18 45 FF 00 00 00 01 80 00 00 00 00 00 29 D3 68 07 00 4B 12 00 00 00 01 00 04 EB 01)

The response to this is not processed by the binding - the response looks like it’s probably correct, but it’s using a different frame than my dongle responds with.

So, if we go back to your first point -:

What version of ZNP/ZStack are you using? Mine is probably a year or two old - I’m guessing your might be newer and it might have different configuration/defaults.

The response your device is using looks very interesting but it’s not fully documented in the doc version I was using when I wrote this so I’ll see if the later docs have it.

HI, have tryed to add a device, this is the Xiaomi Mi Smart Home Temperature / Humidity Sensor , https://xiaomi-mi.com/sockets-and-sensors/xiaomi-mi-temperature-humidity-sensor/

My cc2531 stated online in paperui but when i try to add a device thru Inbox->Search for things -> Zigbee

Here my debug log :

Please assist.
Br Mickel

It looks like your dongle is also returning the same as @curlyel. I dont think this is impacting associations, but it is certainly an issue. Can you tell me what version of ZDP you are running.

In future, can you provide logs as a file please - not an image.

Hi Chris,
I’ve had just little time this and last night, but also failed binding the Xiaomi sensors. Z-Stack is 1.2.2a.44539 (pretty old, I guess), with the Secure_LinkKeyJoin.hex firmware. Is this combo expected to work?
I do also see a lot of unhandled 0x45ff packets. I haven’t had time to look further into the logs.

Tonight I got the ccsniffer python script (USB<->wireshark) work in case you need packet captures. Or is just the openhab.log enough for debugging?

Thanks for your effort!

These are ZDO messages - my dongle doesn’t seem to send the ZDO messages in this packet.[quote=“mwick83, post:54, topic:15763”]
Or is just the openhab.log enough for debugging?
[/quote]

Yes - until I work out the issue with the 45ff packets it’s not going to work. It’s strange that my system isn’t sending these - it sends specific ZDO messages. I’d actually much prefer the way your system is sending them as it looks like it’s a global message for all ZDO which is much preferred.

I’ll look to update my dongle (if I can find my programmer :wink: ).

Hi Chris, i’m using the Z-Stack Home 1.2.2a.44539 with the CC2531ZNP-Pro-Secure_Standard.hex firmware.

Logfiles will be provided as a file in future (sorry)

Mickel

Interesting - both you guys are running the same version. it’s been so long since I programmed my dongle I don’t remember what version it has. I might try programming a stick with the latest ZNP3.

I’ll post another update tonight hopefully - this will add a log entry that reads back the version. Then we can avoid relying on remembering what versions we loaded (well, hopefully!).

The version string will look like this -:

CC2531 version is Software=2.5 Product=0 Hardware=1 Transport=2

I’ve put the update on the download site. This has a few mostly minor changes, but I’d like to see what version your system is reporting.

Thanks for looking into it @chris.
Here is my version:

 CC2531 version is Software=2.6 Product=0 Hardware=3 Transport=2

btw.: Meanwhile, I’ve resetted some HUE bulb too just to check if it’s different - It’s the same as the Osram - not found during discovery. Since you suspect the issue to be related to the ZNP firmware version, I guess this is expected result… :wink:

Thanks.

As above, it won’t work until we solve this issue. At the moment the stack isn’t receiving the ZDO messages - until this is resolved pretty much nothing will work and changing devices won’t help I’m afraid.

I’ll take a look at this later - I’ll try and add a handler for this based on your logs. Can you also post the hex file that you used so I can look to program a stick with this version for testing?