Zigbee binding

Sure :wink:

It is CC2531ZNP-Pro-Secure_Standard.hex
taken from “Z-Stack Home 1.2.2a.44539”

At the time of downloading it, it was the latest Z-Stack before Zigbee 3.0 if I got TI right…

Ok, thanks. I’ll try and find this version on the TI website.

I’ve upload some different versions on my Github that you might want to try out!
:


https://github.com/mtornblad/zstack (This is an old one)

//Mattias

Ok, good news is one of my (many) other sticks is reporting the following -:

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

This is now exhibiting the same functionality as you see so this is good :slight_smile: .

Just for the record:
CC2531 version is Software=2.6 Product=0 Hardware=3 Transport=2

Chris: I also downloaded TI’s ZStack v3, but I couldn’t find binaries for flashing in their package, so I didn’t want to go through the hassle of setting up compiler/debugger environment for the CC2531.

HI Chris, you said “I’ve put the update on the download site.” but i can;t find it on git (the compiled JAR file)
Can you provide link? then i also can report my cc2531 version.
Thank
Mickel

It should be available in the marketplace - alternatively, here for manual download -:

Chris, Thanks, Didn’t see the link in the history post.

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

Hi @chris;
just noticed, that README.md at github.com/openhab/org.openhab.binding.zigbee
has been changed few days ago, pointing now to TI’s Z-Stack-Home version 1.2.2a explicitly:

  Z-STACK-HOME v.1.2.2a package. The file name is CC2531ZNP-Pro-Secure_Standard.hex ...

The reported version of the included firmware file is then “Software=2.6” - right?
I’m curious, if you have have something working meanwhile in combination with this version of the dongle?

Do you have a new .jar for us to play with or should we go one step back and flash an older firmware on the dongle?
:wink:

There is an updated JAR (in the same place as above) - it fixes a couple of issues relating to the TI stick but I’ve not tested it fully with the firmware you are using - I tried to program the stick last night but something wasn’t working somewhere (I had to dig out an old PC as I normally use a Mac).

Please let me know what you find as someone is having other trouble at the moment that I think might be related to trust issues and it would be good to see if others have the same problem - I find my older TI stick works fine.

Oh yes, the behaviour has changed, some progress here :slight_smile:
I can discover the HUE as well as the Osram bulbs:

The Osrams remains “unitialized” while the HUE gets online and offers three channels (switching, dimmin, color), but I cannot link any of them.

This is the log from HUE discovery:

2017-05-19 15:54:08.677 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- AF_DATA_CONFIRM (FE 03 44 80 E9 01 7E 51)
2017-05-19 15:54:08.678 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: AF_DATA_CONFIRM(Endpoint=1, Status=Z_MAC_NO_ACK(233), TransID=126)
2017-05-19 15:54:17.511 [DEBUG] [zigbee.internal.ZigBeeHandlerFactory] - Creating coordinator handler for org.eclipse.smarthome.core.thing.internal.ThingImpl@1e791d4e
2017-05-19 15:54:17.532 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - Initializing ZigBee thing handler zigbee:device:af6da79d:001788011051e6ae.
2017-05-19 15:54:17.538 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 001788011051E6AE: Coordinator status changed to ONLINE.
2017-05-19 15:54:17.542 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 001788011051E6AE: Coordinator is ONLINE. Starting device initialisation.
2017-05-19 15:54:17.555 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 001788011051E6AE: Initialising node
2017-05-19 15:54:17.557 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 001788011051E6AE: Processing device 40021/242, cluster 33
2017-05-19 15:54:17.559 [DEBUG] [handler.cluster.ZigBeeClusterHandler] - Cluster converter for cluster 33 is not implemented!
2017-05-19 15:54:17.562 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 001788011051E6AE: Processing device 40021/11, cluster 0
2017-05-19 15:54:17.563 [DEBUG] [handler.cluster.ZigBeeClusterHandler] - Cluster converter for cluster 0 is not implemented!
2017-05-19 15:54:17.566 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 001788011051E6AE: Processing device 40021/11, cluster 3
2017-05-19 15:54:17.568 [DEBUG] [handler.cluster.ZigBeeClusterHandler] - Cluster converter for cluster 3 is not implemented!
2017-05-19 15:54:17.570 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 001788011051E6AE: Processing device 40021/11, cluster 4
2017-05-19 15:54:17.571 [DEBUG] [handler.cluster.ZigBeeClusterHandler] - Cluster converter for cluster 4 is not implemented!
2017-05-19 15:54:17.573 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 001788011051E6AE: Processing device 40021/11, cluster 5
2017-05-19 15:54:17.575 [DEBUG] [handler.cluster.ZigBeeClusterHandler] - Cluster converter for cluster 5 is not implemented!
2017-05-19 15:54:17.577 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 001788011051E6AE: Processing device 40021/11, cluster 6
2017-05-19 15:54:17.581 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 001788011051E6AE: Processing device 40021/11, cluster 8
2017-05-19 15:54:17.584 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 001788011051E6AE: Processing device 40021/11, cluster 768
2017-05-19 15:54:17.587 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 001788011051E6AE: Processing device 40021/11, cluster 4096
2017-05-19 15:54:17.589 [DEBUG] [handler.cluster.ZigBeeClusterHandler] - Cluster converter for cluster 4096 is not implemented!
2017-05-19 15:54:17.591 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 001788011051E6AE: Created 3 channels
2017-05-19 15:54:17.608 [DEBUG] [er.cluster.ZigBeeOnOffClusterHandler] - 001788011051E6AE: Initialising device on/off cluster
2017-05-19 15:54:17.610 [DEBUG] [.zsmartsystems.zigbee.zcl.ZclCluster] - readSync request: ZclAttribute [id=0, name=OnOff, dataType=BOOLEAN, lastValue=true]
2017-05-19 15:54:17.611 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 8418260000D9959B: Manufacturer request timeout
2017-05-19 15:54:17.611 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 001788011051E6AE: Model request timeout
2017-05-19 15:54:17.612 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ReadAttributesCommand [On/Off: 0/0 -> 40021/11, cluster=0006, TID=81, identifiers=[Attribute Identifier: attributeIdentifier=0]]
2017-05-19 15:54:17.613 [DEBUG] [.zsmartsystems.zigbee.zcl.ZclCluster] - readSync request: ZclAttribute [id=5, name=ModelIdentifier, dataType=CHARACTER_STRING, lastValue=Classic A60 TW]
2017-05-19 15:54:17.614 [DEBUG] [.zsmartsystems.zigbee.zcl.ZclCluster] - readSync request: ZclAttribute [id=3, name=HWVersion, dataType=UNSIGNED_8_BIT_INTEGER, lastValue=17]
2017-05-19 15:54:17.616 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ReadAttributesCommand [Basic: 0/0 -> 60164/3, cluster=0000, TID=82, identifiers=[Attribute Identifier: attributeIdentifier=5]]
2017-05-19 15:54:17.617 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ReadAttributesCommand [Basic: 0/0 -> 40021/11, cluster=0000, TID=83, identifiers=[Attribute Identifier: attributeIdentifier=3]]
2017-05-19 15:54:17.615 [DEBUG] [31.network.impl.CommandInterfaceImpl] - ->  AF_DATA_REQUEST (Packet: subsystem=null, length=15, apiId=24 01, data=FE 0F 24 01 55 9C 0B 01 06 00 81 30 1F 05 00 81 00 00 00 C5, checksum=C5, error=false)
2017-05-19 15:54:17.647 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-  AF_DATA_SRSP (FE 01 64 01 00 64)
2017-05-19 15:54:17.649 [DEBUG] [31.network.impl.CommandInterfaceImpl] - ->  AF_DATA_REQUEST (Packet: subsystem=null, length=15, apiId=24 01, data=FE 0F 24 01 55 9C 0B 01 00 00 83 30 1F 05 00 83 00 03 00 C0, checksum=C0, error=false)
2017-05-19 15:54:17.667 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- AF_DATA_CONFIRM (FE 03 44 80 00 01 81 47)
2017-05-19 15:54:17.669 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: AF_DATA_CONFIRM(Endpoint=1, Status=SUCCESS(0), TransID=129)
2017-05-19 15:54:17.679 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- AF_INCOMING_MSG (FE 1C 44 81 00 00 06 00 55 9C 0B 01 00 6B 00 50 8F 13 00 00 08 18 81 01 00 00 00 10 01 55 9C 1D EE)

Do you need other parts of the log or other tests to perform here?

When setting item linking to “simple mode”, I can link the three channels of the HUE and control them via PaperUI:

So how to investigate the Osram behaviour? Somthing to watch for in the debug log?

That’s what I found so far related to the Osram:


2017-05-19 16:33:36.661 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: AF_DATA_CONFIRM(Endpoint=1, Status=SUCCESS(0), TransID=76)
2017-05-19 16:33:36.681 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- AF_INCOMING_MSG (FE 2C 44 81 00 00 00 00 04 EB 03 01 00 1F 00 D6 9E 28 00 00 18 08 4C 01 06 00 00 42 10 32 30  31 34 30 33 33 31 43 4E 4C 53 2A 2A 2A 2A 04 EB 1D 94)
2017-05-19 16:33:36.684 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=44, apiId=44 81, data=FE 2C 44 81 00 00 00 00 04 EB 03 01 00 1 F 00 D6 9E 28 00 00 18 08 4C 01 06 00 00 42 10 32 30 31 34 30 33 33 31 43 4E 4C 53 2A 2A 2A 2A 04 EB 1D 94, checksum=94, error=false
2017-05-19 16:33:36.688 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ReadAttributesResponse [Basic: 60164/3 -> 0/1, cluster=0000, TID=4C, records=[Read Attribute Status Recor d: attributeDataType=CHARACTER_STRING, attributeIdentifier=6, status=0, attributeValue=20140331CNLS****]]
2017-05-19 16:33:36.695 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 8418260000D9959B: ZigBee node property discovery complete: {zigbee_manufacturer=OSRAM, zigbee_logicaltype=ROUTER,  zigbee_powerlevel=FULL, zigbee_model=Classic A60 TW, zigbee_networkaddress=60164, zigbee_powersource=MAINS, zigbee_stkversion=2, zigbee_datecode=20140331CNLS****, zigbee_zclversion=1, z igbee_hwversion=1, zigbee_appversion=7, zigbee_powermode=RECEIVER_ON_IDLE, zigbee_powersources=[MAINS, RECHARGABLE_BATTERY, DISPOSABLE_BATTERY]}
2017-05-19 16:33:36.698 [INFO ] [bee.discovery.ZigBeeDiscoveryService] - 8418260000D9959B: Update ZigBee device zigbee:device with bridge zigbee:coordinator_cc2531:af6da79d
2017-05-19 16:33:43.181 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 001788011051E6AE: ZCL version request timeout
2017-05-19 16:33:43.188 [DEBUG] [.zsmartsystems.zigbee.zcl.ZclCluster] - readSync request: ZclAttribute [id=1, name=ApplicationVersion, dataType=UNSIGNED_8_BIT_INTEGER, lastValue=2]
2017-05-19 16:33:43.192 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 001788011051E6AE: Manufacturer request timeout
2017-05-19 16:33:43.192 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ReadAttributesCommand [Basic: 0/0 -> 40021/11, cluster=0000, TID=4D, identifiers=[Attribute Identifier: a ttributeIdentifier=1]]
2017-05-19 16:33:43.194 [DEBUG] [.zsmartsystems.zigbee.zcl.ZclCluster] - readSync request: ZclAttribute [id=5, name=ModelIdentifier, dataType=CHARACTER_STRING, lastValue=LCT007]
2017-05-19 16:33:43.197 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ReadAttributesCommand [Basic: 0/0 -> 40021/11, cluster=0000, TID=4E, identifiers=[Attribute Identifier: a ttributeIdentifier=5]]
2017-05-19 16:33:43.199 [DEBUG] [31.network.impl.CommandInterfaceImpl] - ->  AF_DATA_REQUEST (Packet: subsystem=null, length=15, apiId=24 01, data=FE 0F 24 01 55 9C 0B 01 00 00 4D 30 1F  05 00 4D 00 01 00 C2, checksum=C2, error=false)

Good news - something works :slight_smile: .

In the short log you posted, i see the following -:

8418260000D9959B: ZigBee node property discovery complete: {zigbee_manufacturer=OSRAM, zigbee_logicaltype=ROUTER, zigbee_powerlevel=FULL, zigbee_model=Classic A60 TW, zigbee_networkaddress=60164, zigbee_powersource=MAINS, zigbee_stkversion=2, zigbee_datecode=20140331CNLS****, zigbee_zclversion=1, zigbee_hwversion=1, zigbee_appversion=7, zigbee_powermode=RECEIVER_ON_IDLE, zigbee_powersources=[MAINS, RECHARGABLE_BATTERY, DISPOSABLE_BATTERY]}

This seems to indicate that the Osram bulb is found ok, and all the properties look to have been discovered ok. Next I see things like -:

001788011051E6AE: ZCL version request timeout

This is the Hue - so it looks like the Hue isn’t responding to these requests. It might be interesting to see a larger log during startup

Need to mention, that I’ve powered off the HUE that time, just have the Osram powered. So think the timeout for the HUE is o.k. at this time.

This is the Hue - so it looks like the Hue isn’t responding to these requests. It might be interesting to see a larger log during startup

So I’ve deleted the thing created for the Osram and reseted the bulb -> starting the discovery again observing the following exception:

2017-05-19 19:37:31.053 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - Initializing ZigBee thing handler zigbee:device:af6da79d:8418260000d9959b.
2017-05-19 19:37:31.065 [ERROR] [ome.core.thing.internal.ThingManager] - Exception occured while initializing handler of thing 'zigbee:device:af6da79d:8418260000d9959b': java.lang.NumberFormatException: For input string: "8418260000D9959B"
java.util.concurrent.ExecutionException: java.lang.NumberFormatException: For input string: "8418260000D9959B"
        at java.util.concurrent.FutureTask.report(FutureTask.java:122)[:1.8.0_131]
        at java.util.concurrent.FutureTask.get(FutureTask.java:206)[:1.8.0_131]
        at org.eclipse.smarthome.core.common.SafeMethodCaller.callAsynchronous(SafeMethodCaller.java:188)[98:org.eclipse.smarthome.core:0.9.0.b4]
        at org.eclipse.smarthome.core.common.SafeMethodCaller.call(SafeMethodCaller.java:81)[98:org.eclipse.smarthome.core:0.9.0.b4]
        at org.eclipse.smarthome.core.common.SafeMethodCaller.call(SafeMethodCaller.java:65)[98:org.eclipse.smarthome.core:0.9.0.b4]
        at org.eclipse.smarthome.core.thing.internal.ThingManager$9.run(ThingManager.java:710)[105:org.eclipse.smarthome.core.thing:0.9.0.b4]
        at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)[:1.8.0_131]
        at java.util.concurrent.FutureTask.run(FutureTask.java:266)[:1.8.0_131]
        at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$201(ScheduledThreadPoolExecutor.java:180)[:1.8.0_131]
        at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:293)[:1.8.0_131]
        at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)[:1.8.0_131]
        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)[:1.8.0_131]
        at java.lang.Thread.run(Thread.java:748)[:1.8.0_131]
Caused by: java.lang.NumberFormatException: For input string: "8418260000D9959B"
        at java.lang.NumberFormatException.forInputString(NumberFormatException.java:65)[:1.8.0_131]
        at java.lang.Long.parseLong(Long.java:592)[:1.8.0_131]
        at com.zsmartsystems.zigbee.IeeeAddress.<init>(IeeeAddress.java:29)[217:org.openhab.binding.zigbee:2.1.0.201705171742]
        at org.openhab.binding.zigbee.handler.ZigBeeThingHandler.initialize(ZigBeeThingHandler.java:77)[217:org.openhab.binding.zigbee:2.1.0.201705171742]
        at org.eclipse.smarthome.core.thing.internal.ThingManager$9$1.call(ThingManager.java:713)[105:org.eclipse.smarthome.core.thing:0.9.0.b4]
        at org.eclipse.smarthome.core.thing.internal.ThingManager$9$1.call(ThingManager.java:1)[105:org.eclipse.smarthome.core.thing:0.9.0.b4]
        at org.eclipse.smarthome.core.common.SafeMethodCaller$CallableWrapper.call(SafeMethodCaller.java:179)[98:org.eclipse.smarthome.core:0.9.0.b4]
        at java.util.concurrent.FutureTask.run(FutureTask.java:266)[:1.8.0_131]
        ... 3 more

… and here is the full log :wink:

2017-05-19 19:37:22.335 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - Starting ZigBee scan for zigbee:coordinator_cc2531:af6da79d
2017-05-19 19:37:22.338 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - Permit join for 60 seconds.
2017-05-19 19:37:22.342 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ManagementPermitJoiningRequest [0/0 -> 65532/0, cluster=0036, TID=0C, permitDuration=60, tcSignificance=true]
2017-05-19 19:37:22.345 [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 0C 30 1F 03 00 3C 01 00, checksum=00, error=false)
2017-05-19 19:37:22.349 [INFO ] [bee.discovery.ZigBeeDiscoveryService] - 001788011051E6AE: Starting ZigBee device discovery
2017-05-19 19:37:22.349 [INFO ] [bee.discovery.ZigBeeDiscoveryService] - 8418260000D9959B: Starting ZigBee device discovery
2017-05-19 19:37:22.386 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-  AF_DATA_SRSP (FE 01 64 01 00 64)
2017-05-19 19:37:22.401 [INFO ] [bee.discovery.ZigBeeDiscoveryService] - 001788011051E6AE: Creating ZigBee device zigbee:device with bridge zigbee:coordinator_cc2531:af6da79d
2017-05-19 19:37:22.402 [INFO ] [bee.discovery.ZigBeeDiscoveryService] - 8418260000D9959B: Creating ZigBee device zigbee:device with bridge zigbee:coordinator_cc2531:af6da79d
2017-05-19 19:37:22.412 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 001788011051E6AE: ZigBee node property discovery start
2017-05-19 19:37:22.416 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 001788011051E6AE: ZigBee node property discovery using 40021/11
2017-05-19 19:37:22.417 [INFO ] [g.discovery.internal.PersistentInbox] - Added new thing 'zigbee:device:af6da79d:8418260000d9959b' to inbox.
2017-05-19 19:37:22.419 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 8418260000D9959B: ZigBee node property discovery start
2017-05-19 19:37:22.419 [DEBUG] [.zsmartsystems.zigbee.zcl.ZclCluster] - readSync request: ZclAttribute [id=4, name=ManufacturerName, dataType=CHARACTER_STRING, lastValue=Philips]
2017-05-19 19:37:22.461 [DEBUG] [ee.internal.ZigBeeNetworkMeshMonitor] - 40021: ManagementLqiRequest returned null
2017-05-19 19:37:22.461 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ReadAttributesCommand [Basic: 0/0 -> 40021/11, cluster=0000, TID=0D, identifiers=[Attribute Identifier: attributeIdentifier=4]]
2017-05-19 19:37:22.463 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: IeeeAddressRequest [0/0 -> 40021/0, cluster=0001, TID=0E, nwkAddrOfInterest=40021, requestType=1, startIndex=0]
2017-05-19 19:37:22.464 [DEBUG] [31.network.impl.CommandInterfaceImpl] - ->  AF_DATA_REQUEST (Packet: subsystem=null, length=15, apiId=24 01, data=FE 0F 24 01 55 9C 0B 01 00 00 0D 30 1F 05 00 0D 00 04 00 C7, checksum=C7, error=false)
2017-05-19 19:37:22.463 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 8418260000D9959B: ZigBee node property discovery using 60164/3
2017-05-19 19:37:22.468 [DEBUG] [.zsmartsystems.zigbee.zcl.ZclCluster] - readSync request: ZclAttribute [id=4, name=ManufacturerName, dataType=CHARACTER_STRING, lastValue=OSRAM]
2017-05-19 19:37:22.470 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ReadAttributesCommand [Basic: 0/0 -> 60164/3, cluster=0000, TID=0F, identifiers=[Attribute Identifier: attributeIdentifier=4]]
2017-05-19 19:37:22.496 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-  AF_DATA_SRSP (FE 01 64 01 00 64)
2017-05-19 19:37:22.497 [DEBUG] [31.network.impl.CommandInterfaceImpl] - ->  AF_DATA_REQUEST (Packet: subsystem=null, length=15, apiId=24 01, data=FE 0F 24 01 04 EB 03 01 00 00 0F 30 1F 05 00 0F 00 04 00 E9, checksum=E9, error=false)
2017-05-19 19:37:22.528 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-  AF_DATA_SRSP (FE 01 64 01 00 64)
2017-05-19 19:37:22.530 [DEBUG] [31.network.impl.CommandInterfaceImpl] - ->  AF_DATA_REQUEST (Packet: subsystem=null, length=15, apiId=24 01, data=FE 0F 24 01 55 9C 00 00 01 00 0E 30 1F 05 00 55 9C 01 00 0E, checksum=0E, error=false)
2017-05-19 19:37:22.557 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-  AF_DATA_SRSP (FE 01 64 01 00 64)
2017-05-19 19:37:22.570 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- AF_DATA_CONFIRM (FE 03 44 80 00 01 0F C9)
2017-05-19 19:37:22.571 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: AF_DATA_CONFIRM(Endpoint=1, Status=SUCCESS(0), TransID=15)
2017-05-19 19:37:22.603 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- AF_INCOMING_MSG (FE 21 44 81 00 00 00 00 04 EB 03 01 00 51 00 C1 5F 38 00 00 0D 08 0F 01 04 00 00 42 05 4F 53 52 41 4D 04 EB 1D 06)
2017-05-19 19:37:22.604 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=33, apiId=44 81, data=FE 21 44 81 00 00 00 00 04 EB 03 01 00 51 00 C1 5F 38 00 00 0D 08 0F 01 04 00 00 42 05 4F 53 52 41 4D 04 EB 1D 06, checksum=06, error=false
2017-05-19 19:37:22.606 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ReadAttributesResponse [Basic: 60164/3 -> 0/1, cluster=0000, TID=0F, records=[Read Attribute Status Record: attributeDataType=CHARACTER_STRING, attributeIdentifier=4, status=0, attributeValue=OSRAM]]
2017-05-19 19:37:22.613 [DEBUG] [.zsmartsystems.zigbee.zcl.ZclCluster] - readSync request: ZclAttribute [id=5, name=ModelIdentifier, dataType=CHARACTER_STRING, lastValue=Classic A60 TW]
2017-05-19 19:37:22.615 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ReadAttributesCommand [Basic: 0/0 -> 60164/3, cluster=0000, TID=10, identifiers=[Attribute Identifier: attributeIdentifier=5]]
2017-05-19 19:37:22.617 [DEBUG] [31.network.impl.CommandInterfaceImpl] - ->  AF_DATA_REQUEST (Packet: subsystem=null, length=15, apiId=24 01, data=FE 0F 24 01 04 EB 03 01 00 00 10 30 1F 05 00 10 00 05 00 E8, checksum=E8, error=false)
2017-05-19 19:37:22.649 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-  AF_DATA_SRSP (FE 01 64 01 00 64)
2017-05-19 19:37:22.679 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- AF_DATA_CONFIRM (FE 03 44 80 00 01 10 D6)
2017-05-19 19:37:22.680 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: AF_DATA_CONFIRM(Endpoint=1, Status=SUCCESS(0), TransID=16)
2017-05-19 19:37:22.711 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- AF_INCOMING_MSG (FE 2A 44 81 00 00 00 00 04 EB 03 01 00 4E 00 0A 61 38 00 00 16 08 10 01 05 00 00 42 0E 43 6C 61 73 73 69 63 20 41 36 30 20 54 57 04 EB 1D AB)
2017-05-19 19:37:22.712 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=42, apiId=44 81, data=FE 2A 44 81 00 00 00 00 04 EB 03 01 00 4E 00 0A 61 38 00 00 16 08 10 01 05 00 00 42 0E 43 6C 61 73 73 69 63 20 41 36 30 20 54 57 04 EB 1D AB, checksum=AB, error=false
2017-05-19 19:37:22.714 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ReadAttributesResponse [Basic: 60164/3 -> 0/1, cluster=0000, TID=10, records=[Read Attribute Status Record: attributeDataType=CHARACTER_STRING, attributeIdentifier=5, status=0, attributeValue=Classic A60 TW]]
2017-05-19 19:37:22.719 [DEBUG] [.zsmartsystems.zigbee.zcl.ZclCluster] - readSync request: ZclAttribute [id=3, name=HWVersion, dataType=UNSIGNED_8_BIT_INTEGER, lastValue=1]
2017-05-19 19:37:22.721 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ReadAttributesCommand [Basic: 0/0 -> 60164/3, cluster=0000, TID=11, identifiers=[Attribute Identifier: attributeIdentifier=3]]
2017-05-19 19:37:22.722 [DEBUG] [31.network.impl.CommandInterfaceImpl] - ->  AF_DATA_REQUEST (Packet: subsystem=null, length=15, apiId=24 01, data=FE 0F 24 01 04 EB 03 01 00 00 11 30 1F 05 00 11 00 03 00 EE, checksum=EE, error=false)
2017-05-19 19:37:22.753 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-  AF_DATA_SRSP (FE 01 64 01 00 64)
2017-05-19 19:37:22.808 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- AF_DATA_CONFIRM (FE 03 44 80 00 01 11 D7)
2017-05-19 19:37:22.812 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: AF_DATA_CONFIRM(Endpoint=1, Status=SUCCESS(0), TransID=17)
2017-05-19 19:37:22.840 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- AF_INCOMING_MSG (FE 1C 44 81 00 00 00 00 04 EB 03 01 00 4E 00 A1 62 38 00 00 08 08 11 01 03 00 00 20 01 04 EB 1D 41)
2017-05-19 19:37:22.843 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=28, apiId=44 81, data=FE 1C 44 81 00 00 00 00 04 EB 03 01 00 4E 00 A1 62 38 00 00 08 08 11 01 03 00 00 20 01 04 EB 1D 41, checksum=41, error=false
2017-05-19 19:37:22.847 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ReadAttributesResponse [Basic: 60164/3 -> 0/1, cluster=0000, TID=11, records=[Read Attribute Status Record: attributeDataType=UNSIGNED_8_BIT_INTEGER, attributeIdentifier=3, status=0, attributeValue=1]]
2017-05-19 19:37:22.855 [DEBUG] [.zsmartsystems.zigbee.zcl.ZclCluster] - readSync request: ZclAttribute [id=2, name=StackVersion, dataType=UNSIGNED_8_BIT_INTEGER, lastValue=2]
2017-05-19 19:37:22.857 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ReadAttributesCommand [Basic: 0/0 -> 60164/3, cluster=0000, TID=12, identifiers=[Attribute Identifier: attributeIdentifier=2]]
2017-05-19 19:37:22.859 [DEBUG] [31.network.impl.CommandInterfaceImpl] - ->  AF_DATA_REQUEST (Packet: subsystem=null, length=15, apiId=24 01, data=FE 0F 24 01 04 EB 03 01 00 00 12 30 1F 05 00 12 00 02 00 EF, checksum=EF, error=false)
2017-05-19 19:37:22.892 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-  AF_DATA_SRSP (FE 01 64 01 00 64)
2017-05-19 19:37:22.921 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- AF_DATA_CONFIRM (FE 03 44 80 00 01 12 D4)
2017-05-19 19:37:22.926 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: AF_DATA_CONFIRM(Endpoint=1, Status=SUCCESS(0), TransID=18)
2017-05-19 19:37:22.936 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- AF_INCOMING_MSG (FE 1C 44 81 00 00 00 00 04 EB 03 01 00 4E 00 D5 63 38 00 00 08 08 12 01 02 00 00 20 02 04 EB 1D 35)
2017-05-19 19:37:22.940 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=28, apiId=44 81, data=FE 1C 44 81 00 00 00 00 04 EB 03 01 00 4E 00 D5 63 38 00 00 08 08 12 01 02 00 00 20 02 04 EB 1D 35, checksum=35, error=false
2017-05-19 19:37:22.944 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ReadAttributesResponse [Basic: 60164/3 -> 0/1, cluster=0000, TID=12, records=[Read Attribute Status Record: attributeDataType=UNSIGNED_8_BIT_INTEGER, attributeIdentifier=2, status=0, attributeValue=2]]
2017-05-19 19:37:22.952 [DEBUG] [.zsmartsystems.zigbee.zcl.ZclCluster] - readSync request: ZclAttribute [id=0, name=ZCLVersion, dataType=UNSIGNED_8_BIT_INTEGER, lastValue=1]
2017-05-19 19:37:22.953 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ReadAttributesCommand [Basic: 0/0 -> 60164/3, cluster=0000, TID=13, identifiers=[Attribute Identifier: attributeIdentifier=0]]
2017-05-19 19:37:22.955 [DEBUG] [31.network.impl.CommandInterfaceImpl] - ->  AF_DATA_REQUEST (Packet: subsystem=null, length=15, apiId=24 01, data=FE 0F 24 01 04 EB 03 01 00 00 13 30 1F 05 00 13 00 00 00 ED, checksum=ED, error=false)
2017-05-19 19:37:22.986 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-  AF_DATA_SRSP (FE 01 64 01 00 64)
2017-05-19 19:37:23.011 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- AF_DATA_CONFIRM (FE 03 44 80 00 01 13 D5)
2017-05-19 19:37:23.015 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: AF_DATA_CONFIRM(Endpoint=1, Status=SUCCESS(0), TransID=19)
2017-05-19 19:37:23.040 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- AF_INCOMING_MSG (FE 1C 44 81 00 00 00 00 04 EB 03 01 00 4E 00 1A 65 38 00 00 08 08 13 01 00 00 00 20 01 04 EB 1D FC)
2017-05-19 19:37:23.044 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=28, apiId=44 81, data=FE 1C 44 81 00 00 00 00 04 EB 03 01 00 4E 00 1A 65 38 00 00 08 08 13 01 00 00 00 20 01 04 EB 1D FC, checksum=FC, error=false
2017-05-19 19:37:23.048 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ReadAttributesResponse [Basic: 60164/3 -> 0/1, cluster=0000, TID=13, records=[Read Attribute Status Record: attributeDataType=UNSIGNED_8_BIT_INTEGER, attributeIdentifier=0, status=0, attributeValue=1]]
2017-05-19 19:37:23.055 [DEBUG] [.zsmartsystems.zigbee.zcl.ZclCluster] - readSync request: ZclAttribute [id=1, name=ApplicationVersion, dataType=UNSIGNED_8_BIT_INTEGER, lastValue=7]
2017-05-19 19:37:23.057 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ReadAttributesCommand [Basic: 0/0 -> 60164/3, cluster=0000, TID=14, identifiers=[Attribute Identifier: attributeIdentifier=1]]
2017-05-19 19:37:23.059 [DEBUG] [31.network.impl.CommandInterfaceImpl] - ->  AF_DATA_REQUEST (Packet: subsystem=null, length=15, apiId=24 01, data=FE 0F 24 01 04 EB 03 01 00 00 14 30 1F 05 00 14 00 01 00 EC, checksum=EC, error=false)
2017-05-19 19:37:23.088 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-  AF_DATA_SRSP (FE 01 64 01 00 64)
2017-05-19 19:37:23.116 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- AF_DATA_CONFIRM (FE 03 44 80 00 01 14 D2)
2017-05-19 19:37:23.121 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: AF_DATA_CONFIRM(Endpoint=1, Status=SUCCESS(0), TransID=20)
2017-05-19 19:37:23.142 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- AF_INCOMING_MSG (FE 1C 44 81 00 00 00 00 04 EB 03 01 00 51 00 50 66 38 00 00 08 08 14 01 01 00 00 20 07 04 EB 1D AA)
2017-05-19 19:37:23.147 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=28, apiId=44 81, data=FE 1C 44 81 00 00 00 00 04 EB 03 01 00 51 00 50 66 38 00 00 08 08 14 01 01 00 00 20 07 04 EB 1D AA, checksum=AA, error=false
2017-05-19 19:37:23.153 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ReadAttributesResponse [Basic: 60164/3 -> 0/1, cluster=0000, TID=14, records=[Read Attribute Status Record: attributeDataType=UNSIGNED_8_BIT_INTEGER, attributeIdentifier=1, status=0, attributeValue=7]]
2017-05-19 19:37:23.163 [DEBUG] [.zsmartsystems.zigbee.zcl.ZclCluster] - readSync request: ZclAttribute [id=6, name=DateCode, dataType=CHARACTER_STRING, lastValue=20140331CNLS****]
2017-05-19 19:37:23.165 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ReadAttributesCommand [Basic: 0/0 -> 60164/3, cluster=0000, TID=15, identifiers=[Attribute Identifier: attributeIdentifier=6]]
2017-05-19 19:37:23.167 [DEBUG] [31.network.impl.CommandInterfaceImpl] - ->  AF_DATA_REQUEST (Packet: subsystem=null, length=15, apiId=24 01, data=FE 0F 24 01 04 EB 03 01 00 00 15 30 1F 05 00 15 00 06 00 EB, checksum=EB, error=false)
2017-05-19 19:37:23.183 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- AF_DATA_CONFIRM (FE 03 44 80 E9 01 0D 22)
2017-05-19 19:37:23.184 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: AF_DATA_CONFIRM(Endpoint=1, Status=Z_MAC_NO_ACK(233), TransID=13)
2017-05-19 19:37:23.198 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-  AF_DATA_SRSP (FE 01 64 01 00 64)
2017-05-19 19:37:23.222 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- AF_DATA_CONFIRM (FE 03 44 80 00 01 15 D3)
2017-05-19 19:37:23.225 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: AF_DATA_CONFIRM(Endpoint=1, Status=SUCCESS(0), TransID=21)
2017-05-19 19:37:23.242 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- AF_INCOMING_MSG (FE 2C 44 81 00 00 00 00 04 EB 03 01 00 4E 00 86 67 38 00 00 18 08 15 01 06 00 00 42 10 32 30 31 34 30 33 33 31 43 4E 4C 53 2A 2A 2A 2A 04 EB 1D 25)
2017-05-19 19:37:23.246 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: Packet: subsystem=null, length=44, apiId=44 81, data=FE 2C 44 81 00 00 00 00 04 EB 03 01 00 4E 00 86 67 38 00 00 18 08 15 01 06 00 00 42 10 32 30 31 34 30 33 33 31 43 4E 4C 53 2A 2A 2A 2A 04 EB 1D 25, checksum=25, error=false
2017-05-19 19:37:23.249 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ReadAttributesResponse [Basic: 60164/3 -> 0/1, cluster=0000, TID=15, records=[Read Attribute Status Record: attributeDataType=CHARACTER_STRING, attributeIdentifier=6, status=0, attributeValue=20140331CNLS****]]
2017-05-19 19:37:23.257 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 8418260000D9959B: ZigBee node property discovery complete: {zigbee_manufacturer=OSRAM, zigbee_logicaltype=ROUTER, zigbee_powerlevel=FULL, zigbee_model=Classic A60 TW, zigbee_networkaddress=60164, zigbee_powersource=MAINS, zigbee_stkversion=2, zigbee_datecode=20140331CNLS****, zigbee_zclversion=1, zigbee_hwversion=1, zigbee_appversion=7, zigbee_powermode=RECEIVER_ON_IDLE, zigbee_powersources=[MAINS, RECHARGABLE_BATTERY, DISPOSABLE_BATTERY]}
2017-05-19 19:37:23.259 [INFO ] [bee.discovery.ZigBeeDiscoveryService] - 8418260000D9959B: Update ZigBee device zigbee:device with bridge zigbee:coordinator_cc2531:af6da79d
2017-05-19 19:37:31.033 [DEBUG] [zigbee.internal.ZigBeeHandlerFactory] - Creating coordinator handler for org.eclipse.smarthome.core.thing.internal.ThingImpl@7d705278
2017-05-19 19:37:31.053 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - Initializing ZigBee thing handler zigbee:device:af6da79d:8418260000d9959b.
2017-05-19 19:37:31.065 [ERROR] [ome.core.thing.internal.ThingManager] - Exception occured while initializing handler of thing 'zigbee:device:af6da79d:8418260000d9959b': java.lang.NumberFormatException: For input string: "8418260000D9959B"
java.util.concurrent.ExecutionException: java.lang.NumberFormatException: For input string: "8418260000D9959B"
        at java.util.concurrent.FutureTask.report(FutureTask.java:122)[:1.8.0_131]
        at java.util.concurrent.FutureTask.get(FutureTask.java:206)[:1.8.0_131]
        at org.eclipse.smarthome.core.common.SafeMethodCaller.callAsynchronous(SafeMethodCaller.java:188)[98:org.eclipse.smarthome.core:0.9.0.b4]
        at org.eclipse.smarthome.core.common.SafeMethodCaller.call(SafeMethodCaller.java:81)[98:org.eclipse.smarthome.core:0.9.0.b4]
        at org.eclipse.smarthome.core.common.SafeMethodCaller.call(SafeMethodCaller.java:65)[98:org.eclipse.smarthome.core:0.9.0.b4]
        at org.eclipse.smarthome.core.thing.internal.ThingManager$9.run(ThingManager.java:710)[105:org.eclipse.smarthome.core.thing:0.9.0.b4]
        at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)[:1.8.0_131]
        at java.util.concurrent.FutureTask.run(FutureTask.java:266)[:1.8.0_131]
        at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$201(ScheduledThreadPoolExecutor.java:180)[:1.8.0_131]
        at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:293)[:1.8.0_131]
        at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)[:1.8.0_131]
        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)[:1.8.0_131]
        at java.lang.Thread.run(Thread.java:748)[:1.8.0_131]
Caused by: java.lang.NumberFormatException: For input string: "8418260000D9959B"
        at java.lang.NumberFormatException.forInputString(NumberFormatException.java:65)[:1.8.0_131]
        at java.lang.Long.parseLong(Long.java:592)[:1.8.0_131]
        at com.zsmartsystems.zigbee.IeeeAddress.<init>(IeeeAddress.java:29)[217:org.openhab.binding.zigbee:2.1.0.201705171742]
        at org.openhab.binding.zigbee.handler.ZigBeeThingHandler.initialize(ZigBeeThingHandler.java:77)[217:org.openhab.binding.zigbee:2.1.0.201705171742]
        at org.eclipse.smarthome.core.thing.internal.ThingManager$9$1.call(ThingManager.java:713)[105:org.eclipse.smarthome.core.thing:0.9.0.b4]
        at org.eclipse.smarthome.core.thing.internal.ThingManager$9$1.call(ThingManager.java:1)[105:org.eclipse.smarthome.core.thing:0.9.0.b4]
        at org.eclipse.smarthome.core.common.SafeMethodCaller$CallableWrapper.call(SafeMethodCaller.java:179)[98:org.eclipse.smarthome.core:0.9.0.b4]
        at java.util.concurrent.FutureTask.run(FutureTask.java:266)[:1.8.0_131]
        ... 3 more
2017-05-19 19:37:32.498 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 001788011051E6AE: Manufacturer request timeout
2017-05-19 19:37:32.500 [DEBUG] [.zsmartsystems.zigbee.zcl.ZclCluster] - readSync request: ZclAttribute [id=5, name=ModelIdentifier, dataType=CHARACTER_STRING, lastValue=LCT007]
2017-05-19 19:37:32.505 [DEBUG] [ee.internal.ZigBeeNetworkMeshMonitor] - 40021: Ieee Address request returned null
2017-05-19 19:37:32.505 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ReadAttributesCommand [Basic: 0/0 -> 40021/11, cluster=0000, TID=16, identifiers=[Attribute Identifier: attributeIdentifier=5]]
2017-05-19 19:37:32.507 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ManagementRoutingRequest [0/0 -> 40021/0, cluster=0032, TID=17, startIndex=0]
2017-05-19 19:37:32.509 [DEBUG] [31.network.impl.CommandInterfaceImpl] - ->  AF_DATA_REQUEST (Packet: subsystem=null, length=12, apiId=24 01, data=FE 0C 24 01 55 9C 00 00 32 00 17 30 1F 02 00 00 E8, checksum=E8, error=false)
2017-05-19 19:37:32.535 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-  AF_DATA_SRSP (FE 01 64 01 00 64)
2017-05-19 19:37:32.537 [DEBUG] [31.network.impl.CommandInterfaceImpl] - ->  AF_DATA_REQUEST (Packet: subsystem=null, length=15, apiId=24 01, data=FE 0F 24 01 55 9C 0B 01 00 00 16 30 1F 05 00 16 00 05 00 C6, checksum=C6, error=false)
2017-05-19 19:37:32.566 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-  AF_DATA_SRSP (FE 01 64 01 00 64)
2017-05-19 19:37:33.217 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- AF_DATA_CONFIRM (FE 03 44 80 E9 01 16 39)
2017-05-19 19:37:33.219 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: AF_DATA_CONFIRM(Endpoint=1, Status=Z_MAC_NO_ACK(233), TransID=22)
2017-05-19 19:37:42.537 [DEBUG] [ee.internal.ZigBeeNetworkMeshMonitor] - 40021: ManagementRoutingRequest returned null
2017-05-19 19:37:42.539 [DEBUG] [ee.internal.ZigBeeNetworkMeshMonitor] - 40021: Ending mesh update
2017-05-19 19:37:42.541 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 001788011051E6AE: Node updated - IEEE=001788011051E6AE, NWK=9C55, Type=ROUTER
2017-05-19 19:37:42.569 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 001788011051E6AE: Model request timeout
2017-05-19 19:37:42.571 [DEBUG] [.zsmartsystems.zigbee.zcl.ZclCluster] - readSync request: ZclAttribute [id=3, name=HWVersion, dataType=UNSIGNED_8_BIT_INTEGER, lastValue=17]
2017-05-19 19:37:42.573 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ReadAttributesCommand [Basic: 0/0 -> 40021/11, cluster=0000, TID=18, identifiers=[Attribute Identifier: attributeIdentifier=3]]
2017-05-19 19:37:42.575 [DEBUG] [31.network.impl.CommandInterfaceImpl] - ->  AF_DATA_REQUEST (Packet: subsystem=null, length=15, apiId=24 01, data=FE 0F 24 01 55 9C 0B 01 00 00 18 30 1F 05 00 18 00 03 00 C0, checksum=C0, error=false)
2017-05-19 19:37:42.602 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-  AF_DATA_SRSP (FE 01 64 01 00 64)
2017-05-19 19:37:43.367 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- AF_DATA_CONFIRM (FE 03 44 80 E9 01 18 37)
2017-05-19 19:37:43.369 [DEBUG] [31.network.impl.CommandInterfaceImpl] - Received Async Cmd: AF_DATA_CONFIRM(Endpoint=1, Status=Z_MAC_NO_ACK(233), TransID=24)
2017-05-19 19:37:46.489 [DEBUG] [ee.internal.ZigBeeNetworkMeshMonitor] - 0: Starting mesh update
2017-05-19 19:37:46.491 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ManagementLqiRequest [0/0 -> 0/0, cluster=0031, TID=19, startIndex=0]
2017-05-19 19:37:46.493 [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 19 30 1F 02 00 00 2C, checksum=2C, error=false)
2017-05-19 19:37:46.514 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-  AF_DATA_SRSP (FE 01 64 01 00 64)
2017-05-19 19:37:46.526 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-- ZToolPacket (FE 39 45 FF 00 00 00 31 80 00 00 00 00 00 02 00 02 29 D3 68 07 00 4B 12 00 9B 95 D9 00 00 26 18 84 04 EB 15 02 01 4E 29 D3 68 07 00 4B 12 00 AE E6 51 10 01 88 17 00 55 9C 15 02 01 00 A0)
2017-05-19 19:37:46.528 [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 29 D3 68 07 00 4B 12 00 9B 95 D9 00 00 26 18 84 04 EB 15 02 01 4E 29 D3 68 07 00 4B 12 00 AE E6 51 10 01 88 17 00 55 9C 15 02 01 00 A0, checksum=A0, error=false
2017-05-19 19:37:46.532 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ManagementLqiResponse [0/0 -> 0/0, cluster=8031, TID=NULL, status=SUCCESS, neighborTableEntries=2, startIndex=0, neighborTableListCount=2, neighborTableList=[NeighborTable [extendedPanId=124B000768D329, extendedAddress=8418260000D9959B, networkAddress=60164, deviceType=ROUTER, rxOnWhenIdle=RX_ON, relationship=CHILD, permitJoining=UNKNOWN, depth=1, lqi=78], NeighborTable [extendedPanId=124B000768D329, extendedAddress=001788011051E6AE, networkAddress=40021, deviceType=ROUTER, rxOnWhenIdle=RX_ON, relationship=CHILD, permitJoining=UNKNOWN, depth=1, lqi=0]]]
2017-05-19 19:37:46.543 [DEBUG] [ee.internal.ZigBeeNetworkMeshMonitor] - 60164: Starting mesh update
2017-05-19 19:37:46.543 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: IeeeAddressRequest [0/0 -> 0/0, cluster=0001, TID=1A, nwkAddrOfInterest=0, requestType=1, startIndex=0]
2017-05-19 19:37:46.548 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ManagementLqiRequest [0/0 -> 60164/0, cluster=0031, TID=1B, startIndex=0]
2017-05-19 19:37:46.549 [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 1A 30 1F 05 00 00 00 01 00 1A, checksum=1A, error=false)
2017-05-19 19:37:46.573 [DEBUG] [31.network.impl.CommandInterfaceImpl] - <-  AF_DATA_SRSP (FE 01 64 01 00 64)
2017-05-19 19:37:46.575 [DEBUG] [31.network.impl.CommandInterfaceImpl] - ->  AF_DATA_REQUEST (Packet: subsystem=null, length=12, apiId=24 01, data=FE 0C 24 01 04 EB 00 00 31 00 1B 30 1F 02 00 00 C1, checksum=C1, error=false)

Hi Chris, I also did some tests , but with the Xiaomi Mi Smart Home Temperature / Humidity Sensor still fails.
Same error message : Unhandled ZToolPacket type 0x45ca.
Still using CC2531 version : Software=2.6 Product=0 Hardware=3 Transport=2 with CC2531ZNP-Pro-Secure_Standard.hex

@curlyel, Did you also use the FW CC2531ZNP-Pro-Secure_Standard.hex?

@mickelluiten I tried this Xiaomi temp/hum sensor also. It kinda works for me, i.e. I can see the attribute reports in the log. I’m using the Secure_LinkKeyJoin.hex, as I posted before. But no things are showing up in the UI. I will post the logs tomorrow.

OK thanks, Just tryed the CC2531ZNP-Pro-Secure_LinkKeyJoin.hex firmware, but still having the Unhandled ZToolPacket type 0x45ca. message.

@mickelluiten as posted above:

I made som progress since downloading the updated jar from Chris again today…

That’s not an error - it’s a debug message. It just means that the device has contacted the TC (although it doesn’t confirm that the device associated into the network). It’s not a message that I currently handle as there’s nothing really to do.

I’ve been reading up about the Xiaomi devices - they aren’t ZigBee compliant and seem to do some strange (ie non-compliant) things :frowning: . I believe I can make it work, but it’s likely to need some work and the question will be how much of a bodge it is…

I’ve updated the binding again and I hope this should be fixed - the issue is I was using a ‘long’ to store addresses, and that only works for a certain range (ie it needs an unsigned long - java is quite annoying in this respect!).